启动后，只连上蓝牙后，播放音乐，这时按音量，播放，暂停都没功能（按键是有作用的）

[17:15:49][20180713_17:15:49]
[17:16:05]鹁P
CHIP=best2000
[17:16:05]AUD_SEC_SIZE=0
[17:16:05]USER_SEC_SIZE=0x1000
[17:16:05]FACT_SEC_SIZE=0x1000
[17:16:05]FLASH_BASE=0x38000000
[17:16:05]FLASH_SIZE=0x100000
[17:16:05]BUILD_DATE=Jul 13 2018 17:06:14
[17:16:05]REV_INFO=21a42e1-dirty:tlcvoice_earphone
[17:16:05]
[17:16:05]------
[17:16:05]CHIP_ID: 2
[17:16:05]------
[17:16:05]
[17:16:05]
[17:16:05]app_init
[17:16:05]
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]force APP_BATTERY_CHARGER_PLUGOUT
[17:16:05]pmu_charger_irq_handler REG_5E=0x09DB
[17:16:05]pmu_charger_irq_handler DITHERING
[17:16:05]app_battery_charger_handler: status=1
[17:16:05]*** SYSFREQ user=3 freq=2
[17:16:05]*** SYSFREQ top_user=3
[17:16:05]app_battery_handle_process 1,0
[17:16:05]
[17:16:05]==CHARGING-->APP_BATTERY_CHARGER :0==
[17:16:05]
[17:16:05]0
[17:16:05]
[17:16:05]====pwr key (1,1,0)=(14370)==
[17:16:05]
[17:16:05]Dc calib L OK: 0xd014
[17:16:05]Dc calib R OK: 0xb039
[17:16:05]ANA: DC CALIB L=20 R=57
[17:16:05]nv_record_open,crc invalid.
[17:16:05]nv_record_env_new nvrecord_env_p:200001e4
[17:16:05]nv_record_env_init nvrecord_env_p:200001e4
[17:16:05]nvdev_tag,dev sector valid,local name = 2001-tst-54AF
[17:16:05]0x54 0xaf 0xc5 0x10 0x24 0x28
[17:16:05]
[17:16:05]=====first flash status:ee==
[17:16:05]
[17:16:05]app_bt_stream_volume_ptr_update default
[17:16:05]app_status_indication_set 0
[17:16:05]app_voice_report 0
[17:16:05]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 0
[17:16:05]bt_media_start media_active = 2,curr_active_media = 0
[17:16:05]set current media = 2
[17:16:05]app_bt_stream_open prev:9 cur:7 freq:0
[17:16:05]app_play_audio_onoff work:0 op:1 aud_id:0
[17:16:05]app_overlay_select id:1:8
[17:16:05][af_stream_open] id = 0, stream = 0
[17:16:05]codec_int_open: running=0
[17:16:05]trig codec open
[17:16:05]codec_hw_open
[17:16:05]codec_hw_open: codec_hw_state=0
[17:16:05]codec_int_stream_open: stream=0
[17:16:05]codec_int_stream_setup: stream=0
[17:16:05][sample_rate]old = 0, new = 16000
[17:16:05][bits]old = 0, new = 16
[17:16:05][channel_num]old = 0, new = 2
[17:16:05][channel_map]old = 0x0, new = 0x0
[17:16:05][use_dma]old = 0, new = 1
[17:16:05][io_path]old = 0, new = 3
[17:16:05][codec_int_stream_setup]stream = 0, set_flag = ff
[17:16:05][hal_codec_setup_stream] playback sample_rate=16000
[17:16:05]codec_int_stream_start: stream=0
[17:16:05]codec_hw_start: stream=0
[17:16:05]app_poweron_key_init
[17:16:06]cache underflow
[17:16:06]media_sbc_decoder underflow len:256
[17:16:06]
[17:16:06]app_play_sbc_more_data-->need close, length:3920 len:256 l:0
[17:16:06]app_bt_stream_close prev:7 cur:7
[17:16:06]app_play_audio_onoff work:1 op:0 aud_id:0
[17:16:06]codec_int_stream_stop: stream=0
[17:16:06]codec_hw_stop: stream=0
[17:16:06]codec_int_stream_close: stream=0
[17:16:06]codec_int_close: type=0 running=1
[17:16:06]trig codec close
[17:16:06]codec_hw_close
[17:16:06]codec_hw_close: codec_hw_state=2
[17:16:06]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:16:06]bt_media_stop media_active = 2,curr_active_media = 2
[17:16:06]set current media = 0
[17:16:06]bt_media_stop end media_active = 0,curr_active_media = 0
[17:16:08]app_poweron_scan 1,15
[17:16:08]powon raw case:5 time:3034
[17:16:08]hello world i'm bes1000 hahaha case:5
[17:16:08]
[17:16:08]app_status_indication_set 1
[17:16:08]0x40087044=aaaafffb
[17:16:08]0xa7=0
[17:16:08]0xa7=58
[17:16:09]6941 6137 6338 6259
[17:16:09]172f 142f 1532 1433
[17:16:09]6539 6636 6939 6939
[17:16:09]182b 182b 172d 172e
[17:16:09]6841 6440 6242 653b
[17:16:09]1a2a 1a2a 1a2a 1a2a
[17:16:09]6248 5a3f 5f4a 5b41
[17:16:09]1a29 1a29 1a29 1a29
[17:16:09]6048 6046 6142 6249
[17:16:09]1b29 1b29 1b29 1b29
[17:16:09]612f 6446 6347 6540
[17:16:09]1b29 1b29 1b29 1b29
[17:16:09]5742 6148 6448 6048
[17:16:09]1b28 1b28 1b29 1b29
[17:16:09]604f 6047 6143 613f
[17:16:09]1b29 1b29 1b28 1b28
[17:16:09]TX DAC VAL:5da5
[17:16:09]
[17:16:09]btdrv_rf_init_ext 0xc2=0x2d
[17:16:09]intersys_tx_thread_id 20003320
[17:16:09]
[17:16:09]intersys_rx_thread_id 200036d8
[17:16:09]
[17:16:09]_sbrk: incr 0
[17:16:09]
[17:16:09]_sbrk: incr 32
[17:16:09]
[17:16:09]Allocate currentDevice 0x20013964
[17:16:09]status 0
[17:16:09]app_bt_mail_process src_thread:0x20003320 request_id:2 enter
[17:16:09]app_bt_mail_process request_id:2 :status:0 exit
[17:16:09]app_bt_mail_process src_thread:0x20003320 request_id:11 enter
[17:16:09]app_bt_mail_process request_id:11 :status:13 exit
[17:16:09]app_bt_mail_process src_thread:0x20003320 request_id:11 enter
[17:16:09]app_bt_mail_process request_id:11 :status:13 exit
[17:16:09]app_bt_mail_process src_thread:0x20003320 request_id:17 enter
[17:16:09] [RX:]
[17:16:09]0e 04 05 03 0c 00
[17:16:09]app_bt_mail_process request_id:17 :status:0 exit
[17:16:09] [RX:]
[17:16:09]0e 04 05 26 0c 00
[17:16:09]app_bt_mail_process src_thread:0x20003320 request_id:8 enter
[17:16:09]app_bt_mail_process request_id:8 :status:0 exit
[17:16:09] [RX:]
[17:16:09]0e 0b 05 05 10 00 fd 03 ff 06 00 04 00
[17:16:09]app_bt_mail_process src_thread:0x20003320 request_id:16 enter
[17:16:09]app_bt_mail_process request_id:16 :status:0 exit
[17:16:09] [RX:]
[17:16:09]0e 04 05 33 0c 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 31 0c 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 18 0c 00
[17:16:09] [RX:]
[17:16:09]0e 06 05 17 0c 00 00 20
[17:16:09]0xe0 0x3a 0x00 0xc0 0x20 0x04 0xfc 0x34 0x21 0xd0
[17:16:09] [RX:]
[17:16:09]0e 04 05 02 fc 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 32 fc 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 40 fc 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 01 0c 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 01 20 00
[17:16:09] [RX:]
[17:16:09]0e 0c 05 01 10 00 08 02 01 08 b0 02 02 01
[17:16:09] [RX:]
[17:16:09]0e 44 05 02 10 00 bf ff ff 03 fe ff 1f ff 0f ff ff 1f f2 0f e8 fe 3f f7 8f ff 1c 00 00 00 61 f7 ff ff 7f 38 00 00 30 f0 ff 0f 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[17:16:09] [RX:]
[17:16:09]0e 0e 05 04 10 00 00 00 bf fe cd fa db fd 7b 87
[17:16:09] [RX:]
[17:16:09]0e 0e 05 04 10 00 01 00 00 00 00 00 00 00 00 00
[17:16:09] [RX:]
[17:16:09]0e 0e 05 04 10 00 02 00 00 00 00 00 00 00 00 00
[17:16:09] [RX:]
[17:16:09]0e 0a 05 09 10 00 54 af c5 10 24 28
[17:16:09] [RX:]
[17:16:09]0e 05 05 44 0c 00 00
[17:16:09] [RX:]
[17:16:09]0e 05 05 5a 0c 00 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 56 0c 00
[17:16:09]app_bt_golbal_handle evt = 101
[17:16:09] [RX:]
[17:16:09]0e 04 05 24 0c 00
[17:16:09]BesbtThread: 20003320
[17:16:09]
[17:16:09] [RX:]
[17:16:09]0e 04 05 13 0c 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 51 fc 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 5b 0c 00
[17:16:09]app_smartvoice_init
[17:16:09] [RX:]
[17:16:09]0e 04 05 52 0c 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 0f 08 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 1c 0c 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 1e 0c 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 43 0c 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 47 0c 00
[17:16:09] [RX:]
[17:16:09]0e 07 05 02 20 00 fb 00 0e
[17:16:09]Set pkt len 251 nv_acl 14
[17:16:09] [RX:]
[17:16:09]0e 04 05 24 20 00
[17:16:09]appm_start_advertising state: 2
[17:16:09]03 03 c0 ff 0b ff ee 03 28 24 10 c5 af 54 01 00 0a 08 53 4d 41 52 54 20 42 4c 45
[17:16:09] [RX:]
[17:16:09]0e 04 05 06 20 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 08 20 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 09 20 00
[17:16:09] [RX:]
[17:16:09]0e 04 05 0a 20 00
[17:16:10]
[17:16:10]
[17:16:10]
[17:16:10]bt_stack_init_done:5
[17:16:10]
[17:16:10]
[17:16:10]
[17:16:10]app_bt_accessmode_timehandler accMode=3
[17:16:10]app_bt_mail_process src_thread:0x2000371c request_id:5 enter
[17:16:10]!!!ME_SetAccessibleMode MEC(accOp).mode = 3, mode = 3
[17:16:10]
[17:16:10]app_bt_mail_process request_id:5 :status:2 exit
[17:16:10] [RX:]
[17:16:10]0e 04 05 1a 0c 00
[17:16:10]app_bt_golbal_handle evt = 10
[17:16:10]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x3
[17:16:10]app_status_indication_set 7
[17:16:10]app_voice_report 7
[17:16:10]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = d
[17:16:10]bt_media_start media_active = 2,curr_active_media = 0
[17:16:10]set current media = 2
[17:16:10]app_bt_stream_open prev:9 cur:7 freq:0
[17:16:10]app_play_audio_onoff work:0 op:1 aud_id:13
[17:16:10]app_overlay_select id:1:8
[17:16:10][af_stream_open] id = 0, stream = 0
[17:16:10]codec_int_open: running=0
[17:16:10]trig codec open
[17:16:10]codec_hw_open
[17:16:10]codec_hw_open: codec_hw_state=1
[17:16:10]codec_int_stream_open: stream=0
[17:16:10]codec_int_stream_setup: stream=0
[17:16:10][sample_rate]old = 16000, new = 16000
[17:16:10][codec_int_stream_setup]stream = 0, set_flag = 2
[17:16:10][hal_codec_setup_stream] playback sample_rate=16000
[17:16:10]codec_int_stream_start: stream=0
[17:16:10]codec_hw_start: stream=0
[17:16:10]!!!app_bt_opening_reconnect:
[17:16:10]
[17:16:10]01 00 00 00 00 00
[17:16:10]02 00 00 00 0d 00
[17:16:10]!!!go to pairing
[17:16:10]
[17:16:10]app_bt_send_request: 0
[17:16:10]
[17:16:10]app_bt_handle_process: 0
[17:16:10]
[17:16:10]app_bt_accessmode_timehandler accMode=3
[17:16:10]app_status_indication_set 2
[17:16:10]app_bt_mail_process src_thread:0x200035e8 request_id:5 enter
[17:16:10]!!!ME_SetAccessibleMode MEC(accOp).mode = 3, mode = 3
[17:16:10]
[17:16:10]app_bt_golbal_handle evt = 10
[17:16:10]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x3
[17:16:10]app_bt_mail_process request_id:5 :status:2 exit
[17:16:10]app_poweron_finished 1,17
[17:16:10]app_key_init
[17:16:10]hal_gpadc_adc2volt_calib LV=416, HV=546, Slope:1923 Intcpt:0
[17:16:10]cache underflow
[17:16:10]media_sbc_decoder underflow len:256
[17:16:10]
[17:16:10]app_play_sbc_more_data-->need close, length:3120 len:256 l:0
[17:16:10]app_bt_stream_close prev:7 cur:7
[17:16:10]app_play_audio_onoff work:1 op:0 aud_id:0
[17:16:10]codec_int_stream_stop: stream=0
[17:16:10]codec_hw_stop: stream=0
[17:16:10]codec_int_stream_close: stream=0
[17:16:10]codec_int_close: type=0 running=1
[17:16:10]trig codec close
[17:16:10]codec_hw_close
[17:16:10]codec_hw_close: codec_hw_state=2
[17:16:10]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:16:10]bt_media_stop media_active = 2,curr_active_media = 2
[17:16:10]set current media = 0
[17:16:10]bt_media_stop end media_active = 0,curr_active_media = 0
[17:16:11]*** SYSFREQ top_user=3
[17:16:11]app_battery_handle_process 0,3708
[17:16:11]checker: id:0 state:0 mode:0 role:0 cmghdl:0 sniffInterva:499/8704 IsAudioUp:18
[17:16:11]0x00 0x00 0x00 0x00 0x00 0x00
[17:16:11]remDev:2000e3e8 a2dp State:0 remDev:0 hf_channel Connected:0 remDev:0
[17:16:14] [RX:]
[17:16:14]04 0a 65 54 7a 34 a1 84 0c 02 7a 01
[17:16:14] [RX:]
[17:16:14]0f 04 00 05 09 04
[17:16:14] [RX:]
[17:16:14]03 0b 00 80 00 65 54 7a 34 a1 84 01 00
[17:16:14] [RX:]
[17:16:14]1b 03 80 00 05
[17:16:14]app_bt_golbal_handle evt = 4
[17:16:14]CONNECT_IND/CNF evt:4 errCode:0x0 newRole:4 activeCons:1
[17:16:14]CONNECT_IND disable role 2000e3e8
[17:16:14]
[17:16:14]BTEVENT_LINK_CONNECT_IND/CNF activeCons:1
[17:16:14]app_bt_send_request: 0
[17:16:14]
[17:16:14]app_bt_handle_process: 0
[17:16:14]
[17:16:14]CONNECT_IND sniff info 2000e3e8
[17:16:14]
[17:16:14]app_bt_accessmode_set access_mode=0
[17:16:14]app_status_indication_set 2
[17:16:14] [RX:]
[17:16:14]0e 07 05 09 08 00 80 00 01
[17:16:14]app_bt_mail_process src_thread:0x20003320 request_id:6 enter
[17:16:14]app_bt_mail_process request_id:6 :status:19 exit
[17:16:14]app_bt_mail_process src_thread:0x20003320 request_id:2 enter
[17:16:14]app_bt_mail_process request_id:2 :status:0 exit
[17:16:14]app_bt_golbal_handle evt = 14
[17:16:14]ROLE_CHANGE eType:0xe errCode:0x0 newRole:1 activeCons:1
[17:16:14] [RX:]
[17:16:14]0e 06 05 0d 08 00 80 00
[17:16:14]app_bt_mail_process src_thread:0x20003320 request_id:8 enter
[17:16:14]app_bt_mail_process request_id:8 :status:0 exit
[17:16:14] [RX:]
[17:16:14]0f 04 00 05 1d 04
[17:16:14]app_bt_mail_process src_thread:0x200035e8 request_id:5 enter
[17:16:14]!!!ME_SetAccessibleMode MEC(accOp).mode = 0, mode = 0
[17:16:14]
[17:16:14]app_bt_mail_process request_id:5 :status:2 exit
[17:16:14] [RX:]
[17:16:14]0f 04 00 05 1b 04
[17:16:14] [RX:]
[17:16:14]0b 0b 00 80 00 bf fe cf fe db ff 7b 87
[17:16:14] [RX:]
[17:16:14]0e 04 05 1a 0c 00
[17:16:14]app_bt_golbal_handle evt = 10
[17:16:14]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x0
[17:16:14] [RX:]
[17:16:14]0f 04 00 05 1c 04
[17:16:14] [RX:]
[17:16:14]23 0d 00 80 00 00 00 01 00 00 00 00 00 00 00
[17:16:14] [RX:]
[17:16:14]0c 08 00 80 00 08 0f 00 07 66
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]38 04 80 00 40 1f
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]13 05 01 80 00 01 00
[17:16:14] [RX:]
[17:16:14]38 04 80 00 00 7d
[17:16:14] [RX:]
[17:16:14]32 09 65 54 7a 34 a1 84 01 00 04
[17:16:14] [RX:]
[17:16:14]31 06 65 54 7a 34 a1 84
[17:16:14] [RX:]
[17:16:14]0e 0a 05 2b 04 00 65 54 7a 34 a1 84
[17:16:15]CPU USAGE: busy=15 light_sleep=85 deep_sleep=0
[17:16:15] [RX:]
[17:16:15]33 0a 65 54 7a 34 a1 84 48 c1 0a 00
[17:16:15] [RX:]
[17:16:15]0e 0a 05 2c 04 00 65 54 7a 34 a1 84
[17:16:15] [RX:]
[17:16:15]36 07 00 65 54 7a 34 a1 84
[17:16:15] [RX:]
[17:16:15]18 17 65 54 7a 34 a1 84 3c 59 a8 6e d5 65 c8 d1 db 8c 3c a9 34 05 0c bf 04
[17:16:15]::BTEVENT_PAIRING_COMPLETE keyType:4
[17:16:15]
[17:16:15]app_voice_report 13
[17:16:15]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = f
[17:16:15]bt_media_start media_active = 2,curr_active_media = 0
[17:16:15]set current media = 2
[17:16:15]app_bt_stream_open prev:9 cur:7 freq:0
[17:16:15]app_play_audio_onoff work:0 op:1 aud_id:15
[17:16:15]app_overlay_select id:1:8
[17:16:15][af_stream_open] id = 0, stream = 0
[17:16:15]codec_int_open: running=0
[17:16:15]trig codec open
[17:16:15]codec_hw_open
[17:16:15]codec_hw_open: codec_hw_state=1
[17:16:15]codec_int_stream_open: stream=0
[17:16:15]codec_int_stream_setup: stream=0
[17:16:15][sample_rate]old = 16000, new = 16000
[17:16:15][codec_int_stream_setup]stream = 0, set_flag = 2
[17:16:15][hal_codec_setup_stream] playback sample_rate=16000
[17:16:15]codec_int_stream_start: stream=0
[17:16:15]codec_hw_start: stream=0
[17:16:15] [RX:]
[17:16:15]08 04 00 80 00 01
[17:16:15]!!!CMGR_CreateDataLink Handler->flags = 0
[17:16:15]
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]38 04 80 00 40 1f
[17:16:15]Allocate currentDevice 0x20013cb0
[17:16:15]status 0
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]::A2DP_EVENT_AVDTP_CONNECT 23 st = 0x2000c690
[17:16:15]
[17:16:15]::A2DP_EVENT_AVDTP_CONNECT 23 st = 0x2000c7f0
[17:16:15]
[17:16:15],version=c054f21
[17:16:15],version=c054f21
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]::A2DP_EVENT_STREAM_OPEN_IND 1
[17:16:15]
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]::A2DP_EVENT_STREAM_OPEN stream_id:0, sample_rate codec.elements 0x80
[17:16:15]
[17:16:15]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:15]app_bt_stream_volume_ptr_update a2dp_vol:12 hfp_vol:12 ptr:0x20000378
[17:16:15]codecType 0x2
[17:16:15]
[17:16:15]::A2DP_EVENT_STREAM_OPEN stream_id:0, aac sample_rate codec.elements 0x1
[17:16:15]
[17:16:15]::A2DP_EVENT_STREAM_OPEN stream_id:0, aac sample_rate 44100
[17:16:15]
[17:16:15]!!!CMGR_CreateDataLink Handler->flags = 0
[17:16:15]
[17:16:15]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:15]app_bt_profile_active_store_ptr_get hfp_act:0 hsp_act:0 a2dp_act:0x0 codec_type=0
[17:16:15]app_bt_profile_connect_manager_a2dp A2DP_EVENT_STREAM_OPEN,codec type=2
[17:16:15]app_voice_report 9
[17:16:15]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1b
[17:16:15]bt_media_start media_active = 2,curr_active_media = 2
[17:16:15]set current media = 2
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]avrcp_callback : chnl 0x2000c95c, Parms 0x20008ca8
[17:16:15]
[17:16:15]::Parms->event 3
[17:16:15]
[17:16:15]::AVRCP_EVENT_DISCONNECT
[17:16:15]app_bt_accessmode_timehandler accMode=3
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]app_bt_mail_process src_thread:0x20003320 request_id:5 enter
[17:16:15]!!!ME_SetAccessibleMode MEC(accOp).mode = 3, mode = 3
[17:16:15]
[17:16:15]app_bt_mail_process request_id:5 :status:2 exit
[17:16:15] [RX:]
[17:16:15]0e 04 05 1a 0c 00
[17:16:15]app_bt_golbal_handle evt = 10
[17:16:15]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x3
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]!!!CMGR_CreateDataLink Handler->flags = 0
[17:16:15]
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]!!!HfIndicatorCallback hf.ind_test.num:7
[17:16:15]
[17:16:15]::HF_EVENT_CALL_IND chan_id:0, call:0
[17:16:15]
[17:16:15]app_media_update_media
[17:16:15]skip BT_STREAM_MEDIA
[17:16:15]::HF_EVENT_CALLSETUP_IND chan_id:0, callSetup = 0
[17:16:15]
[17:16:15]::HF_EVENT_CALLHELD_IND chan_id:0 0 HELD_STATUS = 0
[17:16:15]
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15] [RX:]
[17:16:15]13 05 01 80 00 01 00
[17:16:15]channelid is 0x42, mux's channelid is 0x42
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16]channelid is 0x42, mux's channelid is 0x42
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16]channelid is 0x42, mux's channelid is 0x42
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16]::HF_EVENT_SERVICE_CONNECTED Chan_id:0
[17:16:16]
[17:16:16]hfp get vol raw:10 loc:12
[17:16:16]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:16]app_bt_profile_active_store_ptr_get hfp_act:0 hsp_act:0 a2dp_act:0x1 codec_type=2
[17:16:16]app_bt_profile_connect_manager_hf HF_EVENT_SERVICE_CONNECTED
[17:16:16]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c284
[17:16:16]
[17:16:16]channelid is 0x42, mux's channelid is 0x42
[17:16:16]channelid is 0x42, mux's channelid is 0x42
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c2c8
[17:16:16]
[17:16:16]channelid is 0x42, mux's channelid is 0x42
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c2fc
[17:16:16]
[17:16:16]channelid is 0x42, mux's channelid is 0x42
[17:16:16]cache underflow
[17:16:16]media_sbc_decoder underflow len:256
[17:16:16]
[17:16:16]app_play_sbc_more_data-->need close, length:5520 len:256 l:0
[17:16:16]app_bt_stream_close prev:7 cur:7
[17:16:16]app_play_audio_onoff work:1 op:0 aud_id:0
[17:16:16]codec_int_stream_stop: stream=0
[17:16:16]codec_hw_stop: stream=0
[17:16:16]codec_int_stream_close: stream=0
[17:16:16]codec_int_close: type=0 running=1
[17:16:16]trig codec close
[17:16:16]codec_hw_close
[17:16:16]codec_hw_close: codec_hw_state=2
[17:16:16]app_bt_stream_open prev:9 cur:7 freq:0
[17:16:16]app_play_audio_onoff work:0 op:1 aud_id:27
[17:16:16]app_overlay_select id:1:8
[17:16:16][af_stream_open] id = 0, stream = 0
[17:16:16]codec_int_open: running=0
[17:16:16]trig codec open
[17:16:16]codec_hw_open
[17:16:16]codec_hw_open: codec_hw_state=1
[17:16:16]codec_int_stream_open: stream=0
[17:16:16]codec_int_stream_setup: stream=0
[17:16:16][sample_rate]old = 16000, new = 16000
[17:16:16][codec_int_stream_setup]stream = 0, set_flag = 2
[17:16:16][hal_codec_setup_stream] playback sample_rate=16000
[17:16:16]codec_int_stream_start: stream=0
[17:16:16]codec_hw_start: stream=0
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:16]
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:16] [RX:]
[17:16:16]13 05 01 80 00 01 00
[17:16:17] [RX:]
[17:16:17]13 05 01 80 00 01 00
[17:16:17] [RX:]
[17:16:17]13 05 01 80 00 01 00
[17:16:17] [RX:]
[17:16:17]13 05 01 80 00 01 00
[17:16:17] [RX:]
[17:16:17]13 05 01 80 00 01 00
[17:16:17]cache underflow
[17:16:17]media_sbc_decoder underflow len:256
[17:16:17]
[17:16:17]app_play_sbc_more_data-->need close, length:5280 len:256 l:0
[17:16:17]app_bt_stream_close prev:7 cur:7
[17:16:17]app_play_audio_onoff work:1 op:0 aud_id:0
[17:16:17]codec_int_stream_stop: stream=0
[17:16:17]codec_hw_stop: stream=0
[17:16:17]codec_int_stream_close: stream=0
[17:16:17]codec_int_close: type=0 running=1
[17:16:17]trig codec close
[17:16:17]codec_hw_close
[17:16:17]codec_hw_close: codec_hw_state=2
[17:16:17]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:16:17]bt_media_stop media_active = 2,curr_active_media = 2
[17:16:17]set current media = 0
[17:16:17]bt_media_stop end media_active = 0,curr_active_media = 0
[17:16:21]::A2DP_EVENT_STREAM_START_IND 2 stream_id:0 0 0
[17:16:21]
[17:16:21]!!!ME_StopSniff remDev2000e3e8
[17:16:21]
[17:16:21]::A2DP_EVENT_STREAM_STARTED 2 stream_id:0 0 1
[17:16:21]
[17:16:21]STREAM MANAGE bt_media_start type= 1,device id = 0,media_id = 21
[17:16:21]bt_media_start media_active = 1,curr_active_media = 0
[17:16:21]app_audio_manager_switch_a2dp switch_a2dp to id:0
[17:16:21]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:21]app_bt_stream_volume_ptr_update a2dp_vol:12 hfp_vol:12 ptr:0x20000378
[17:16:21]set current media = 1
[17:16:21]app_bt_stream_setup prev:9 cur:3 sample:32
[17:16:21]app_bt_stream_open prev:9 cur:3 freq:0
[17:16:21]bt_sbc_player work:0 op:0 freq:0 :sample:44100
[17:16:21]
[17:16:21]bt_media_volume_ptr_update_by_mediatype enter
[17:16:21]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:21]app_bt_stream_volume_ptr_update a2dp_vol:12 hfp_vol:12 ptr:0x20000378
[17:16:21]bt_media_volume_ptr_update_by_mediatype exit
[17:16:21]bt_sbc_player: app_sysfreq_req 2
[17:16:21]app_overlay_select id:4:8
[17:16:21]bt_init_xtal_sync
[17:16:21][af_stream_open] id = 0, stream = 0
[17:16:21]codec_int_open: running=0
[17:16:21]trig codec open
[17:16:21]codec_hw_open
[17:16:21]codec_hw_open: codec_hw_state=1
[17:16:21]codec_int_stream_open: stream=0
[17:16:21]codec_int_stream_setup: stream=0
[17:16:21][sample_rate]old = 16000, new = 44100
[17:16:21][vol]old = 0, new = 12
[17:16:21][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:21][hal_codec_setup_stream] playback sample_rate=44100
[17:16:21]a2dp_audio_init f=42768 20720
[17:16:21]
[17:16:21]codec_int_stream_start: stream=0
[17:16:21]codec_hw_start: stream=0
[17:16:21] [RX:]
[17:16:21]13 05 01 80 00 01 00
[17:16:21]bt_xtal_sync: ms=12156 bit_offset=144
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]bt_xtal_sync: ms=12210 bit_offset=146
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]bt_xtal_sync: ms=12288 bit_offset=148
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]*** SYSFREQ user=3 freq=2
[17:16:21]*** SYSFREQ top_user=3
[17:16:21]app_battery_handle_process 0,3705
[17:16:21]checker: id:0 state:3 mode:0 role:1 cmghdl:2000d700 sniffInterva:0/800 IsAudioUp:0
[17:16:21]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:21]remDev:2000e3e8 a2dp State:3 remDev:2000e3e8 hf_channel Connected:1 remDev:2000e3e8
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]bt_xtal_sync: ms=12368 bit_offset=149
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]bt_xtal_sync: ms=12454 bit_offset=151
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]bt_xtal_sync: ms=12480 bit_offset=152
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:21]bt_xtal_sync: ms=12538 bit_offset=153
[17:16:21]a2dp_audio_more_data cache not ready skip frame 2
[17:16:21]
[17:16:22]bt_xtal_sync: ms=12564 bit_offset=154
[17:16:22]a2dp_audio_more_data cache not ready skip frame 2
[17:16:22]
[17:16:22]a2dp_audio_more_data cache not ready skip frame 2
[17:16:22]
[17:16:22]aac cache ok:2092
[17:16:22]
[17:16:22]bt_xtal_sync: ms=12619 bit_offset=155
[17:16:22]bt_xtal_sync: ms=12698 bit_offset=157
[17:16:22]bt_xtal_sync: ms=12760 bit_offset=158
[17:16:22]bt_xtal_sync: ms=12791 bit_offset=159
[17:16:22]
[17:16:22]
[17:16:22]Update 26M: diff=12 ms=503 ppb=23851
[17:16:22]
[17:16:22]
[17:16:22]bt_xtal_sync: Bad ppb=23851 last_valid_ppb=-2147483648 last_tune_ppb=0 (1)
[17:16:22]bt_xtal_sync: ms=12847 bit_offset=160
[17:16:22]bt_xtal_sync: ms=12873 bit_offset=161
[17:16:22]bt_xtal_sync: ms=12925 bit_offset=162
[17:16:22]bt_xtal_sync: ms=12954 bit_offset=163
[17:16:22]bt_xtal_sync: ms=13006 bit_offset=164
[17:16:22]bt_xtal_sync: ms=13040 bit_offset=165
[17:16:22]bt_xtal_sync: ms=13104 bit_offset=166
[17:16:22]bt_xtal_sync: ms=13133 bit_offset=167
[17:16:22]bt_xtal_sync: ms=13164 bit_offset=168
[17:16:22]bt_xtal_sync: ms=13220 bit_offset=169
[17:16:22]bt_xtal_sync: ms=13281 bit_offset=170
[17:16:22]bt_xtal_sync: ms=13342 bit_offset=172
[17:16:22]bt_xtal_sync: ms=13426 bit_offset=174
[17:16:22]bt_xtal_sync: ms=13488 bit_offset=175
[17:16:23]bt_xtal_sync: ms=13551 bit_offset=177
[17:16:23]
[17:16:23]
[17:16:23]Update 26M: diff=14 ms=625 ppb=22378
[17:16:23]
[17:16:23]
[17:16:23]bt_xtal_sync: Bad ppb=22378 last_valid_ppb=23851 last_tune_ppb=0 (2)
[17:16:23]bt_xtal_sync: ms=13608 bit_offset=178
[17:16:23]bt_xtal_sync: ms=13663 bit_offset=179
[17:16:23]bt_xtal_sync: ms=13695 bit_offset=180
[17:16:23]bt_xtal_sync: ms=13751 bit_offset=181
[17:16:23]bt_xtal_sync: ms=13778 bit_offset=182
[17:16:23]bt_xtal_sync: ms=13831 bit_offset=183
[17:16:23]bt_xtal_sync: ms=13860 bit_offset=184
[17:16:23]bt_xtal_sync: ms=13912 bit_offset=185
[17:16:23]bt_xtal_sync: ms=13980 bit_offset=186
[17:16:23]bt_xtal_sync: ms=14015 bit_offset=187
[17:16:23]bt_xtal_sync: ms=14041 bit_offset=188
[17:16:23]bt_xtal_sync: ms=14105 bit_offset=189
[17:16:23]bt_xtal_sync: ms=14131 bit_offset=190
[17:16:23]bt_xtal_sync: ms=14206 bit_offset=192
[17:16:23]bt_xtal_sync: ms=14280 bit_offset=193
[17:16:23]
[17:16:23]
[17:16:23]Update 26M: diff=12 ms=585 ppb=20513
[17:16:23]
[17:16:23]
[17:16:23]bt_xtal_sync: Bad ppb=20513 last_valid_ppb=22378 last_tune_ppb=0 (3)
[17:16:23]bt_xtal_sync: ms=14318 bit_offset=194
[17:16:23]bt_xtal_sync: ms=14381 bit_offset=196
[17:16:23]bt_xtal_sync: ms=14463 bit_offset=198
[17:16:23],rssi_monitor[0]:338 ,rssi_monitor[1]: 173 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:23]
[17:16:23]bt_xtal_sync: ms=14498 bit_offset=199
[17:16:23]bt_xtal_sync: ms=14561 bit_offset=200
[17:16:24]bt_xtal_sync: ms=14588 bit_offset=201
[17:16:24]bt_xtal_sync: ms=14643 bit_offset=202
[17:16:24]bt_xtal_sync: ms=14733 bit_offset=204
[17:16:24]bt_xtal_sync: ms=14831 bit_offset=206
[17:16:24]bt_xtal_sync: ms=14880 bit_offset=207
[17:16:24]bt_xtal_sync: ms=14943 bit_offset=209
[17:16:24]bt_xtal_sync: ms=15030 bit_offset=211
[17:16:24]
[17:16:24]
[17:16:24]Update 26M: diff=13 ms=566 ppb=22958
[17:16:24]
[17:16:24]
[17:16:24]bt_xtal_sync: Bad ppb=22958 last_valid_ppb=20513 last_tune_ppb=0 (4)
[17:16:24]bt_xtal_sync: ms=15132 bit_offset=213
[17:16:24]bt_xtal_sync: ms=15176 bit_offset=214
[17:16:24]bt_xtal_sync: ms=15203 bit_offset=215
[17:16:24]bt_xtal_sync: ms=15260 bit_offset=216
[17:16:24]bt_xtal_sync: ms=15295 bit_offset=217
[17:16:24]bt_xtal_sync: ms=15355 bit_offset=218
[17:16:24]bt_xtal_sync: ms=15410 bit_offset=219
[17:16:24]bt_xtal_sync: ms=15437 bit_offset=220
[17:16:24]bt_xtal_sync: ms=15503 bit_offset=221
[17:16:24]bt_xtal_sync: ms=15531 bit_offset=222
[17:16:25]CPU USAGE: busy=21 light_sleep=79 deep_sleep=0
[17:16:25]bt_xtal_sync: ms=15593 bit_offset=223
[17:16:25]bt_xtal_sync: ms=15620 bit_offset=224
[17:16:25]bt_xtal_sync: ms=15695 bit_offset=226
[17:16:25]bt_xtal_sync: ms=15800 bit_offset=228
[17:16:25]bt_xtal_sync: ms=15827 bit_offset=229
[17:16:25]
[17:16:25]
[17:16:25]Update 26M: diff=13 ms=623 ppb=20842
[17:16:25]
[17:16:25]
[17:16:25]bt_xtal_sync: Bad ppb=20842 last_valid_ppb=22958 last_tune_ppb=0 (5)
[17:16:25]bt_xtal_sync: ms=15920 bit_offset=231
[17:16:25]bt_xtal_sync: ms=15983 bit_offset=232
[17:16:25]bt_xtal_sync: ms=16019 bit_offset=233
[17:16:25]bt_xtal_sync: ms=16050 bit_offset=234
[17:16:25]bt_xtal_sync: ms=16152 bit_offset=236
[17:16:25]bt_xtal_sync: ms=16238 bit_offset=238
[17:16:25]bt_xtal_sync: ms=16320 bit_offset=240
[17:16:25]bt_xtal_sync: ms=16386 bit_offset=241
[17:16:25]bt_xtal_sync: ms=16418 bit_offset=242
[17:16:25]bt_xtal_sync: ms=16449 bit_offset=243
[17:16:25]bt_xtal_sync: ms=16541 bit_offset=245
[17:16:26]bt_xtal_sync: ms=16591 bit_offset=246
[17:16:26]
[17:16:26]
[17:16:26]Update 26M: diff=12 ms=572 ppb=20961
[17:16:26]
[17:16:26]
[17:16:26]bt_xtal_sync: Bad ppb=20961 last_valid_ppb=20842 last_tune_ppb=0 (6)
[17:16:26]bt_reset_xtal_to_default: Reset xtal to default 45
[17:16:26]bt_xtal_sync: ms=16630 bit_offset=247
[17:16:26]bt_xtal_sync: ms=16696 bit_offset=248
[17:16:26]bt_xtal_sync: ms=16726 bit_offset=249
[17:16:26]bt_xtal_sync: ms=16759 bit_offset=250
[17:16:26],rssi_monitor[0]:210 ,rssi_monitor[1]: 165 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:26]
[17:16:26]bt_xtal_sync: ms=16821 bit_offset=251
[17:16:26]bt_xtal_sync: ms=16853 bit_offset=252
[17:16:26]bt_xtal_sync: ms=16919 bit_offset=253
[17:16:26]bt_xtal_sync: ms=16950 bit_offset=254
[17:16:26]bt_xtal_sync: ms=16989 bit_offset=255
[17:16:26]bt_xtal_sync: ms=17050 bit_offset=256
[17:16:26]bt_xtal_sync: ms=17081 bit_offset=257
[17:16:26]bt_xtal_sync: ms=17144 bit_offset=258
[17:16:26]bt_xtal_sync: ms=17171 bit_offset=259
[17:16:26]bt_xtal_sync: ms=17245 bit_offset=261
[17:16:26]bt_xtal_sync: ms=17308 bit_offset=262
[17:16:26]
[17:16:26]
[17:16:26]Update 26M: diff=12 ms=582 ppb=20601
[17:16:26]
[17:16:26]
[17:16:26]bt_xtal_sync: Bad ppb=20601 last_valid_ppb=20961 last_tune_ppb=0 (1)
[17:16:26]bt_xtal_sync: ms=17363 bit_offset=263
[17:16:26]bt_xtal_sync: ms=17401 bit_offset=264
[17:16:26]bt_xtal_sync: ms=17429 bit_offset=265
[17:16:26]bt_xtal_sync: ms=17503 bit_offset=266
[17:16:26]bt_xtal_sync: ms=17534 bit_offset=267
[17:16:27]bt_xtal_sync: ms=17569 bit_offset=268
[17:16:27]bt_xtal_sync: ms=17632 bit_offset=269
[17:16:27]bt_xtal_sync: ms=17659 bit_offset=270
[17:16:27]bt_xtal_sync: ms=17729 bit_offset=272
[17:16:27]bt_xtal_sync: ms=17818 bit_offset=274
[17:16:27]bt_xtal_sync: ms=17864 bit_offset=275
[17:16:27]bt_xtal_sync: ms=17961 bit_offset=277
[17:16:27]
[17:16:27]
[17:16:27]Update 26M: diff=11 ms=532 ppb=20657
[17:16:27]
[17:16:27]
[17:16:27]bt_xtal_sync: Bad ppb=20657 last_valid_ppb=20601 last_tune_ppb=0 (2)
[17:16:27]bt_xtal_sync: ms=18052 bit_offset=279
[17:16:27]bt_xtal_sync: ms=18106 bit_offset=280
[17:16:27]bt_xtal_sync: ms=18149 bit_offset=281
[17:16:27]bt_xtal_sync: ms=18176 bit_offset=282
[17:16:27]bt_xtal_sync: ms=18235 bit_offset=283
[17:16:27]bt_xtal_sync: ms=18263 bit_offset=284
[17:16:27]bt_xtal_sync: ms=18322 bit_offset=285
[17:16:27]bt_xtal_sync: ms=18384 bit_offset=286
[17:16:27]bt_xtal_sync: ms=18415 bit_offset=287
[17:16:27]bt_xtal_sync: ms=18470 bit_offset=288
[17:16:27]bt_xtal_sync: ms=18498 bit_offset=289
[17:16:27]bt_xtal_sync: ms=18537 bit_offset=290
[17:16:28]bt_xtal_sync: ms=18599 bit_offset=291
[17:16:28]bt_xtal_sync: ms=18630 bit_offset=292
[17:16:28]bt_xtal_sync: ms=18700 bit_offset=293
[17:16:28]
[17:16:28]
[17:16:28]Update 26M: diff=12 ms=551 ppb=21769
[17:16:28]
[17:16:28]
[17:16:28]bt_xtal_sync: Bad ppb=21769 last_valid_ppb=20657 last_tune_ppb=0 (3)
[17:16:28]bt_xtal_sync: ms=18743 bit_offset=294
[17:16:28]bt_xtal_sync: ms=18771 bit_offset=295
[17:16:28]bt_xtal_sync: ms=18825 bit_offset=296
[17:16:28]bt_xtal_sync: ms=18891 bit_offset=298
[17:16:28]bt_xtal_sync: ms=18989 bit_offset=300
[17:16:28]bt_xtal_sync: ms=19020 bit_offset=301
[17:16:28]bt_xtal_sync: ms=19096 bit_offset=302
[17:16:28],rssi_monitor[0]:258 ,rssi_monitor[1]: 168 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:28]
[17:16:28]bt_xtal_sync: ms=19143 bit_offset=303
[17:16:28]bt_xtal_sync: ms=19178 bit_offset=304
[17:16:28]bt_xtal_sync: ms=19245 bit_offset=306
[17:16:28]bt_xtal_sync: ms=19346 bit_offset=308
[17:16:28]bt_xtal_sync: ms=19377 bit_offset=309
[17:16:28]
[17:16:28]
[17:16:28]Update 26M: diff=12 ms=551 ppb=21744
[17:16:28]
[17:16:28]
[17:16:28]bt_xtal_sync: Bad ppb=21744 last_valid_ppb=21769 last_tune_ppb=0 (4)
[17:16:28]bt_xtal_sync: ms=19474 bit_offset=311
[17:16:28]app_bt_accessmode_timehandler accMode=2
[17:16:28]app_bt_mail_process src_thread:0x20003768 request_id:5 enter
[17:16:28]!!!ME_SetAccessibleMode MEC(accOp).mode = 2, mode = 2
[17:16:28]
[17:16:28]app_bt_mail_process request_id:5 :status:2 exit
[17:16:28] [RX:]
[17:16:28]0e 04 05 1a 0c 00
[17:16:28]app_bt_golbal_handle evt = 10
[17:16:29]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x2
[17:16:29]bt_xtal_sync: ms=19571 bit_offset=313
[17:16:29]bt_xtal_sync: ms=19603 bit_offset=314
[17:16:29]bt_xtal_sync: ms=19696 bit_offset=316
[17:16:29]bt_xtal_sync: ms=19751 bit_offset=317
[17:16:29]bt_xtal_sync: ms=19830 bit_offset=319
[17:16:29]
[17:16:29]
[17:16:29]Update 26M: diff=-204 ms=226 ppb=-899174
[17:16:29]
[17:16:29]
[17:16:29]bt_xtal_sync: Bad ppb=-899174 last_valid_ppb=21744 last_tune_ppb=0 (5)
[17:16:29]bt_xtal_sync: ms=19864 bit_offset=320
[17:16:29]bt_xtal_sync: ms=19934 bit_offset=321
[17:16:29]bt_xtal_sync: ms=19966 bit_offset=322
[17:16:29]bt_xtal_sync: ms=19996 bit_offset=323
[17:16:29]bt_xtal_sync: ms=20063 bit_offset=324
[17:16:29]bt_xtal_sync: ms=20090 bit_offset=325
[17:16:29]bt_xtal_sync: ms=20150 bit_offset=326
[17:16:29]bt_xtal_sync: ms=20181 bit_offset=327
[17:16:29]bt_xtal_sync: ms=20240 bit_offset=328
[17:16:29]bt_xtal_sync: ms=20268 bit_offset=329
[17:16:29]bt_xtal_sync: ms=20329 bit_offset=330
[17:16:29]bt_xtal_sync: ms=20371 bit_offset=331
[17:16:29]bt_xtal_sync: ms=20403 bit_offset=332
[17:16:29]bt_xtal_sync: ms=20461 bit_offset=333
[17:16:29]bt_xtal_sync: ms=20489 bit_offset=334
[17:16:29]
[17:16:29]
[17:16:29]Update 26M: diff=12 ms=523 ppb=22939
[17:16:29]
[17:16:29]
[17:16:29]bt_xtal_sync: Bad ppb=22939 last_valid_ppb=-899174 last_tune_ppb=0 (6)
[17:16:29]bt_reset_xtal_to_default: Reset xtal to default 45
[17:16:29]bt_xtal_sync: ms=20545 bit_offset=335
[17:16:30]bt_xtal_sync: ms=20604 bit_offset=336
[17:16:30]bt_xtal_sync: ms=20631 bit_offset=337
[17:16:30]bt_xtal_sync: ms=20690 bit_offset=338
[17:16:30]bt_xtal_sync: ms=20725 bit_offset=339
[17:16:30]bt_xtal_sync: ms=20753 bit_offset=340
[17:16:30]bt_xtal_sync: ms=20819 bit_offset=341
[17:16:30]bt_xtal_sync: ms=20855 bit_offset=342
[17:16:30]bt_xtal_sync: ms=20885 bit_offset=343
[17:16:30]bt_xtal_sync: ms=20980 bit_offset=345
[17:16:30]bt_xtal_sync: ms=21088 bit_offset=347
[17:16:30]bt_xtal_sync: ms=21115 bit_offset=348
[17:16:30]
[17:16:30]
[17:16:30]Update 26M: diff=10 ms=483 ppb=20672
[17:16:30]
[17:16:30]
[17:16:30]bt_xtal_sync: Bad ppb=20672 last_valid_ppb=22939 last_tune_ppb=0 (1)
[17:16:30]bt_xtal_sync: ms=21171 bit_offset=349
[17:16:30]bt_xtal_sync: ms=21206 bit_offset=350
[17:16:30]bt_xtal_sync: ms=21265 bit_offset=351
[17:16:30]bt_xtal_sync: ms=21326 bit_offset=353
[17:16:30]bt_xtal_sync: ms=21381 bit_offset=354
[17:16:30]bt_xtal_sync: ms=21444 bit_offset=355
[17:16:30],rssi_monitor[0]:738 ,rssi_monitor[1]: 198 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:30]
[17:16:30]bt_xtal_sync: ms=21471 bit_offset=356
[17:16:30]bt_xtal_sync: ms=21538 bit_offset=357
[17:16:30]bt_xtal_sync: ms=21565 bit_offset=358
[17:16:31]bt_xtal_sync: ms=21624 bit_offset=359
[17:16:31]bt_xtal_sync: ms=21651 bit_offset=360
[17:16:31]bt_xtal_sync: ms=21718 bit_offset=361
[17:16:31]
[17:16:31]
[17:16:31]Update 26M: diff=10 ms=453 ppb=22039
[17:16:31]
[17:16:31]
[17:16:31]bt_xtal_sync: Bad ppb=22039 last_valid_ppb=20672 last_tune_ppb=0 (2)
[17:16:31]bt_xtal_sync: ms=21750 bit_offset=362
[17:16:31]bt_xtal_sync: ms=21808 bit_offset=363
[17:16:31]bt_xtal_sync: ms=21835 bit_offset=364
[17:16:31]bt_xtal_sync: ms=21891 bit_offset=365
[17:16:31]bt_xtal_sync: ms=21921 bit_offset=366
[17:16:31]bt_xtal_sync: ms=21975 bit_offset=367
[17:16:31]bt_xtal_sync: ms=22009 bit_offset=368
[17:16:31]bt_xtal_sync: ms=22068 bit_offset=369
[17:16:31]bt_xtal_sync: ms=22095 bit_offset=370
[17:16:31]bt_xtal_sync: ms=22134 bit_offset=371
[17:16:31]bt_xtal_sync: ms=22176 bit_offset=372
[17:16:31]bt_xtal_sync: ms=22239 bit_offset=373
[17:16:31]bt_xtal_sync: ms=22274 bit_offset=374
[17:16:31]bt_xtal_sync: ms=22329 bit_offset=375
[17:16:31]bt_xtal_sync: ms=22368 bit_offset=376
[17:16:31]
[17:16:31]
[17:16:31]Update 26M: diff=12 ms=533 ppb=22509
[17:16:31]
[17:16:31]
[17:16:31]bt_xtal_sync: Bad ppb=22509 last_valid_ppb=22039 last_tune_ppb=0 (3)
[17:16:31]bt_xtal_sync: ms=22407 bit_offset=377
[17:16:31]bt_xtal_sync: ms=22466 bit_offset=378
[17:16:31]bt_xtal_sync: ms=22493 bit_offset=379
[17:16:31]bt_xtal_sync: ms=22551 bit_offset=380
[17:16:31]bt_xtal_sync: ms=22579 bit_offset=381
[17:16:32]bt_xtal_sync: ms=22634 bit_offset=382
[17:16:32]bt_xtal_sync: ms=22696 bit_offset=383
[17:16:32]bt_xtal_sync: ms=22724 bit_offset=384
[17:16:32]bt_xtal_sync: ms=22795 bit_offset=385
[17:16:32]bt_xtal_sync: ms=22821 bit_offset=386
[17:16:32]bt_xtal_sync: ms=22860 bit_offset=387
[17:16:32]bt_xtal_sync: ms=22927 bit_offset=389
[17:16:32]app_bt_key 1024,5
[17:16:32]first blood!
[17:16:32]bt_key_send code:1024 evt:5
[17:16:32]bt_key_handle code:1024 evt:5
[17:16:32]
[17:16:32]==2001=vol up ===event:5==
[17:16:32]
[17:16:32]app_bt_stream_volumeup set audio volume
[17:16:32]app_bt_stream_volumeset vol=13
[17:16:32]codec_int_stream_setup: stream=0
[17:16:32][sample_rate]old = 44100, new = 44100
[17:16:32][vol]old = 12, new = 13
[17:16:32][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:32][hal_codec_setup_stream] playback sample_rate=44100
[17:16:32]app_bt_stream_volumeup a2dp: 13
[17:16:32]app_bt_stream_volumeup hfp: 12
[17:16:32]hfp get vol raw:10 loc:12
[17:16:32]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:32]
[17:16:32]channelid is 0x42, mux's channelid is 0x42
[17:16:32] [RX:]
[17:16:32]13 05 01 80 00 01 00
[17:16:32]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:32]
[17:16:32]bt_xtal_sync: ms=23019 bit_offset=391
[17:16:32]bt_xtal_sync: ms=23093 bit_offset=392
[17:16:32]bt_xtal_sync: ms=23159 bit_offset=394
[17:16:32]
[17:16:32]
[17:16:32]Update 26M: diff=14 ms=665 ppb=21033
[17:16:32]
[17:16:32]
[17:16:32]bt_xtal_sync: Bad ppb=21033 last_valid_ppb=22509 last_tune_ppb=0 (4)
[17:16:32]bt_xtal_sync: ms=23251 bit_offset=396
[17:16:32]bt_xtal_sync: ms=23344 bit_offset=398
[17:16:32]bt_xtal_sync: ms=23375 bit_offset=399
[17:16:32]bt_xtal_sync: ms=23414 bit_offset=400
[17:16:32]bt_xtal_sync: ms=23477 bit_offset=401
[17:16:32]bt_xtal_sync: ms=23505 bit_offset=402
[17:16:32]bt_xtal_sync: ms=23559 bit_offset=403
[17:16:33]bt_xtal_sync: ms=23636 bit_offset=404
[17:16:33]bt_xtal_sync: ms=23695 bit_offset=406
[17:16:33],rssi_monitor[0]:242 ,rssi_monitor[1]: 167 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:33]
[17:16:33]bt_xtal_sync: ms=23793 bit_offset=408
[17:16:33]bt_xtal_sync: ms=23828 bit_offset=409
[17:16:33]bt_xtal_sync: ms=23863 bit_offset=410
[17:16:33]
[17:16:33]
[17:16:33]Update 26M: diff=12 ms=487 ppb=24615
[17:16:33]
[17:16:33]
[17:16:33]bt_xtal_sync: Bad ppb=24615 last_valid_ppb=21033 last_tune_ppb=0 (5)
[17:16:33]bt_xtal_sync: ms=23920 bit_offset=411
[17:16:33]bt_xtal_sync: ms=23955 bit_offset=412
[17:16:33]bt_xtal_sync: ms=24021 bit_offset=413
[17:16:33]bt_xtal_sync: ms=24049 bit_offset=414
[17:16:33]bt_xtal_sync: ms=24115 bit_offset=415
[17:16:33]bt_xtal_sync: ms=24143 bit_offset=416
[17:16:33]bt_xtal_sync: ms=24181 bit_offset=417
[17:16:33]bt_xtal_sync: ms=24237 bit_offset=418
[17:16:33]bt_xtal_sync: ms=24268 bit_offset=419
[17:16:33]app_bt_key 1024,5
[17:16:33]first blood!
[17:16:33]bt_key_send code:1024 evt:5
[17:16:33]bt_key_handle code:1024 evt:5
[17:16:33]
[17:16:33]==2001=vol up ===event:5==
[17:16:33]
[17:16:33]app_bt_stream_volumeup set audio volume
[17:16:33]app_bt_stream_volumeset vol=14
[17:16:33]codec_int_stream_setup: stream=0
[17:16:33][sample_rate]old = 44100, new = 44100
[17:16:33][vol]old = 13, new = 14
[17:16:33][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:33][hal_codec_setup_stream] playback sample_rate=44100
[17:16:33]app_bt_stream_volumeup a2dp: 14
[17:16:33]app_bt_stream_volumeup hfp: 12
[17:16:33]hfp get vol raw:10 loc:12
[17:16:33]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:33]
[17:16:33]channelid is 0x42, mux's channelid is 0x42
[17:16:33]bt_xtal_sync: ms=24323 bit_offset=420
[17:16:33] [RX:]
[17:16:33]13 05 01 80 00 01 00
[17:16:33]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:33]
[17:16:33]bt_xtal_sync: ms=24385 bit_offset=421
[17:16:33]bt_xtal_sync: ms=24412 bit_offset=422
[17:16:33]bt_xtal_sync: ms=24468 bit_offset=423
[17:16:33]bt_xtal_sync: ms=24503 bit_offset=424
[17:16:34]bt_xtal_sync: ms=24558 bit_offset=425
[17:16:34]
[17:16:34]
[17:16:34]Update 26M: diff=12 ms=536 ppb=22352
[17:16:34]
[17:16:34]
[17:16:34]bt_xtal_sync: Bad ppb=22352 last_valid_ppb=24615 last_tune_ppb=0 (6)
[17:16:34]bt_reset_xtal_to_default: Reset xtal to default 45
[17:16:34]bt_xtal_sync: ms=24585 bit_offset=426
[17:16:34]bt_xtal_sync: ms=24645 bit_offset=427
[17:16:34]bt_xtal_sync: ms=24695 bit_offset=428
[17:16:34]bt_xtal_sync: ms=24733 bit_offset=429
[17:16:34]bt_xtal_sync: ms=24761 bit_offset=430
[17:16:34]bt_xtal_sync: ms=24823 bit_offset=431
[17:16:34]bt_xtal_sync: ms=24878 bit_offset=432
[17:16:34]bt_xtal_sync: ms=24906 bit_offset=433
[17:16:34]bt_xtal_sync: ms=24965 bit_offset=434
[17:16:34]bt_xtal_sync: ms=24991 bit_offset=435
[17:16:34]bt_xtal_sync: ms=25073 bit_offset=437
[17:16:34]bt_xtal_sync: ms=25163 bit_offset=439
[17:16:34]
[17:16:34]
[17:16:34]Update 26M: diff=10 ms=468 ppb=21362
[17:16:34]
[17:16:34]
[17:16:34]bt_xtal_sync: Bad ppb=21362 last_valid_ppb=22352 last_tune_ppb=0 (1)
[17:16:34]bt_xtal_sync: ms=25206 bit_offset=440
[17:16:34]app_bt_key 1024,5
[17:16:34]first blood!
[17:16:34]bt_key_send code:1024 evt:5
[17:16:34]bt_key_handle code:1024 evt:5
[17:16:34]
[17:16:34]==2001=vol up ===event:5==
[17:16:34]
[17:16:34]app_bt_stream_volumeup set audio volume
[17:16:34]app_bt_stream_volumeset vol=15
[17:16:34]codec_int_stream_setup: stream=0
[17:16:34][sample_rate]old = 44100, new = 44100
[17:16:34][vol]old = 14, new = 15
[17:16:34][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:34][hal_codec_setup_stream] playback sample_rate=44100
[17:16:34]app_bt_stream_volumeup a2dp: 15
[17:16:34]app_bt_stream_volumeup hfp: 12
[17:16:34]hfp get vol raw:10 loc:12
[17:16:34]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:34]
[17:16:34]channelid is 0x42, mux's channelid is 0x42
[17:16:34]bt_xtal_sync: ms=25277 bit_offset=441
[17:16:34] [RX:]
[17:16:34]13 05 01 80 00 01 00
[17:16:34]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:34]
[17:16:34]bt_xtal_sync: ms=25348 bit_offset=443
[17:16:34]bt_xtal_sync: ms=25383 bit_offset=444
[17:16:34]bt_xtal_sync: ms=25442 bit_offset=445
[17:16:34]bt_xtal_sync: ms=25532 bit_offset=447
[17:16:34]bt_xtal_sync: ms=25567 bit_offset=448
[17:16:35]CPU USAGE: busy=21 light_sleep=79 deep_sleep=0
[17:16:35]bt_xtal_sync: ms=25630 bit_offset=449
[17:16:35]bt_xtal_sync: ms=25660 bit_offset=450
[17:16:35]app_bt_accessmode_timehandler accMode=3
[17:16:35]app_bt_mail_process src_thread:0x20003768 request_id:5 enter
[17:16:35]!!!ME_SetAccessibleMode MEC(accOp).mode = 3, mode = 3
[17:16:35]
[17:16:35]app_bt_mail_process request_id:5 :status:2 exit
[17:16:35] [RX:]
[17:16:35]0e 04 05 1a 0c 00
[17:16:35]app_bt_golbal_handle evt = 10
[17:16:35]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x3
[17:16:35]bt_xtal_sync: ms=25720 bit_offset=451
[17:16:35]bt_xtal_sync: ms=25781 bit_offset=452
[17:16:35]bt_xtal_sync: ms=25823 bit_offset=453
[17:16:35]
[17:16:35]
[17:16:35]Update 26M: diff=11 ms=475 ppb=23127
[17:16:35]
[17:16:35]
[17:16:35]bt_xtal_sync: Bad ppb=23127 last_valid_ppb=21362 last_tune_ppb=0 (2)
[17:16:35]bt_xtal_sync: ms=25855 bit_offset=454
[17:16:35]bt_xtal_sync: ms=25921 bit_offset=456
[17:16:35]bt_xtal_sync: ms=25987 bit_offset=457
[17:16:35]bt_xtal_sync: ms=26046 bit_offset=458
[17:16:35]bt_xtal_sync: ms=26081 bit_offset=459
[17:16:35],rssi_monitor[0]:914 ,rssi_monitor[1]: 209 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:35]
[17:16:35]bt_xtal_sync: ms=26120 bit_offset=460
[17:16:35]bt_xtal_sync: ms=26178 bit_offset=461
[17:16:35]bt_xtal_sync: ms=26213 bit_offset=462
[17:16:35]bt_xtal_sync: ms=26271 bit_offset=463
[17:16:35]bt_xtal_sync: ms=26311 bit_offset=464
[17:16:35]bt_xtal_sync: ms=26373 bit_offset=466
[17:16:35]bt_xtal_sync: ms=26411 bit_offset=467
[17:16:35]
[17:16:35]
[17:16:35]Update 26M: diff=10 ms=424 ppb=23564
[17:16:35]
[17:16:35]
[17:16:35]bt_xtal_sync: Bad ppb=23564 last_valid_ppb=23127 last_tune_ppb=0 (3)
[17:16:35]app_bt_key 1024,5
[17:16:35]first blood!
[17:16:35]bt_key_send code:1024 evt:5
[17:16:35]bt_key_handle code:1024 evt:5
[17:16:35]
[17:16:35]==2001=vol up ===event:5==
[17:16:35]
[17:16:35]app_bt_stream_volumeup set audio volume
[17:16:35]app_bt_stream_volumeset vol=16
[17:16:35]codec_int_stream_setup: stream=0
[17:16:35][sample_rate]old = 44100, new = 44100
[17:16:35][vol]old = 15, new = 16
[17:16:35][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:35][hal_codec_setup_stream] playback sample_rate=44100
[17:16:35]app_bt_stream_volumeup a2dp: 16
[17:16:35]app_bt_stream_volumeup hfp: 12
[17:16:35]hfp get vol raw:10 loc:12
[17:16:35]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:35]
[17:16:35]channelid is 0x42, mux's channelid is 0x42
[17:16:35]bt_xtal_sync: ms=26467 bit_offset=468
[17:16:35] [RX:]
[17:16:35]13 05 01 80 00 01 00
[17:16:35]bt_xtal_sync: ms=26498 bit_offset=469
[17:16:35]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:35]
[17:16:35]bt_xtal_sync: ms=26558 bit_offset=470
[17:16:36]bt_xtal_sync: ms=26589 bit_offset=471
[17:16:36]bt_xtal_sync: ms=26645 bit_offset=472
[17:16:36]bt_xtal_sync: ms=26680 bit_offset=473
[17:16:36]bt_xtal_sync: ms=26766 bit_offset=475
[17:16:36]bt_xtal_sync: ms=26875 bit_offset=477
[17:16:36]bt_xtal_sync: ms=26902 bit_offset=478
[17:16:36]bt_xtal_sync: ms=27022 bit_offset=480
[17:16:36]bt_xtal_sync: ms=27052 bit_offset=481
[17:16:36]bt_xtal_sync: ms=27115 bit_offset=482
[17:16:36]
[17:16:36]
[17:16:36]Update 26M: diff=12 ms=556 ppb=21549
[17:16:36]
[17:16:36]
[17:16:36]bt_xtal_sync: Bad ppb=21549 last_valid_ppb=23564 last_tune_ppb=0 (4)
[17:16:36]bt_xtal_sync: ms=27146 bit_offset=483
[17:16:36]bt_xtal_sync: ms=27205 bit_offset=484
[17:16:36]bt_xtal_sync: ms=27232 bit_offset=485
[17:16:36]app_bt_key 1024,5
[17:16:36]first blood!
[17:16:36]bt_key_send code:1024 evt:5
[17:16:36]bt_key_handle code:1024 evt:5
[17:16:36]
[17:16:36]==2001=vol up ===event:5==
[17:16:36]
[17:16:36]app_bt_stream_volumeup set audio volume
[17:16:36]app_bt_stream_volumeset vol=17
[17:16:36]codec_int_stream_setup: stream=0
[17:16:36][sample_rate]old = 44100, new = 44100
[17:16:36][vol]old = 16, new = 17
[17:16:36][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:36][hal_codec_setup_stream] playback sample_rate=44100
[17:16:36]app_bt_stream_volumeup a2dp: 17
[17:16:36]app_bt_stream_volumeup hfp: 12
[17:16:36]hfp get vol raw:10 loc:12
[17:16:36]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:36]
[17:16:36]channelid is 0x42, mux's channelid is 0x42
[17:16:36]bt_xtal_sync: ms=27291 bit_offset=486
[17:16:36]bt_xtal_sync: ms=27318 bit_offset=487
[17:16:36] [RX:]
[17:16:36]13 05 01 80 00 01 00
[17:16:36]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:36]
[17:16:36]bt_xtal_sync: ms=27359 bit_offset=488
[17:16:36]*** SYSFREQ user=3 freq=2
[17:16:36]*** SYSFREQ top_user=3
[17:16:36]app_battery_handle_process 0,3701
[17:16:36]checker: id:0 state:3 mode:0 role:1 cmghdl:2000d700 sniffInterva:0/800 IsAudioUp:0
[17:16:36]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:36]remDev:2000e3e8 a2dp State:3 remDev:2000e3e8 hf_channel Connected:1 remDev:2000e3e8
[17:16:36]bt_xtal_sync: ms=27414 bit_offset=489
[17:16:36]bt_xtal_sync: ms=27441 bit_offset=490
[17:16:36]bt_xtal_sync: ms=27501 bit_offset=491
[17:16:36]bt_xtal_sync: ms=27563 bit_offset=492
[17:16:37]bt_xtal_sync: ms=27589 bit_offset=493
[17:16:37]bt_xtal_sync: ms=27655 bit_offset=494
[17:16:37]bt_xtal_sync: ms=27685 bit_offset=495
[17:16:37]bt_xtal_sync: ms=27716 bit_offset=496
[17:16:37]bt_xtal_sync: ms=27783 bit_offset=497
[17:16:37]
[17:16:37]
[17:16:37]Update 26M: diff=12 ms=551 ppb=21769
[17:16:37]
[17:16:37]
[17:16:37]bt_xtal_sync: Bad ppb=21769 last_valid_ppb=21549 last_tune_ppb=0 (5)
[17:16:37]bt_xtal_sync: ms=27811 bit_offset=498
[17:16:37]bt_xtal_sync: ms=27877 bit_offset=499
[17:16:37]bt_xtal_sync: ms=27911 bit_offset=500
[17:16:37]bt_xtal_sync: ms=27966 bit_offset=501
[17:16:37]bt_xtal_sync: ms=27994 bit_offset=502
[17:16:37]bt_xtal_sync: ms=28053 bit_offset=503
[17:16:37]bt_xtal_sync: ms=28081 bit_offset=504
[17:16:37]bt_xtal_sync: ms=28135 bit_offset=505
[17:16:37]bt_xtal_sync: ms=28171 bit_offset=506
[17:16:37]bt_xtal_sync: ms=28226 bit_offset=507
[17:16:37]bt_xtal_sync: ms=28253 bit_offset=508
[17:16:37]bt_xtal_sync: ms=28303 bit_offset=509
[17:16:37]bt_xtal_sync: ms=28364 bit_offset=510
[17:16:37],rssi_monitor[0]:210 ,rssi_monitor[1]: 165 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:37]
[17:16:37]bt_xtal_sync: ms=28431 bit_offset=512
[17:16:37]app_bt_key 1024,5
[17:16:37]first blood!
[17:16:37]bt_key_send code:1024 evt:5
[17:16:37]bt_key_handle code:1024 evt:5
[17:16:37]
[17:16:37]==2001=vol up ===event:5==
[17:16:37]
[17:16:37]app_bt_stream_volumeup set audio volume
[17:16:37]app_bt_stream_volumeset vol=18
[17:16:37]codec_int_stream_setup: stream=0
[17:16:37][sample_rate]old = 44100, new = 44100
[17:16:37][codec_int_stream_setup]stream = 0, set_flag = 2
[17:16:37][hal_codec_setup_stream] playback sample_rate=44100
[17:16:37]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1d
[17:16:37]bt_media_start media_active = 3,curr_active_media = 1
[17:16:37]set current media = 2
[17:16:37]app_bt_stream_close prev:3 cur:3
[17:16:37]bt_sbc_player work:1 op:1 freq:0 :sample:44100
[17:16:37]
[17:16:37]app_bt_stream_volumeup a2dp: 17
[17:16:37]app_bt_stream_volumeup hfp: 12
[17:16:37]codec_int_stream_stop: stream=0
[17:16:37]hfp get vol raw:10 loc:12
[17:16:37]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:37]
[17:16:37]codec_hw_stop: stream=0
[17:16:37]codec_int_stream_close: stream=0
[17:16:37]codec_int_close: type=0 running=1
[17:16:37]trig codec close
[17:16:37]codec_hw_close
[17:16:37]codec_hw_close: codec_hw_state=2
[17:16:37]bt_term_xtal_sync_default
[17:16:37]app_bt_stream_open prev:9 cur:7 freq:0
[17:16:37]app_play_audio_onoff work:0 op:1 aud_id:29
[17:16:37]app_overlay_select id:1:8
[17:16:37][af_stream_open] id = 0, stream = 0
[17:16:37]codec_int_open: running=0
[17:16:37]trig codec open
[17:16:37]codec_hw_open
[17:16:37]codec_hw_open: codec_hw_state=1
[17:16:37]codec_int_stream_open: stream=0
[17:16:37]codec_int_stream_setup: stream=0
[17:16:37][sample_rate]old = 44100, new = 16000
[17:16:37][vol]old = 17, new = 0
[17:16:37][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:37][hal_codec_setup_stream] playback sample_rate=16000
[17:16:37]codec_int_stream_start: stream=0
[17:16:37]codec_hw_start: stream=0
[17:16:38]channelid is 0x42, mux's channelid is 0x42
[17:16:38] [RX:]
[17:16:38]13 05 01 80 00 01 00
[17:16:38]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:38]
[17:16:38]cache underflow
[17:16:38]media_sbc_decoder underflow len:256
[17:16:38]
[17:16:38]app_play_sbc_more_data-->need close, length:2480 len:256 l:0
[17:16:38]app_bt_stream_close prev:7 cur:7
[17:16:38]app_play_audio_onoff work:1 op:0 aud_id:0
[17:16:38]codec_int_stream_stop: stream=0
[17:16:38]codec_hw_stop: stream=0
[17:16:38]codec_int_stream_close: stream=0
[17:16:38]codec_int_close: type=0 running=1
[17:16:38]trig codec close
[17:16:38]codec_hw_close
[17:16:38]codec_hw_close: codec_hw_state=2
[17:16:38]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:16:38]bt_media_stop media_active = 3,curr_active_media = 2
[17:16:38]app_audio_manager_switch_a2dp switch_a2dp to id:0
[17:16:38]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:38]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:16:38]set current media = 1
[17:16:38]bt_media_stop end media_active = 1,curr_active_media = 1
[17:16:38]app_bt_stream_setup prev:9 cur:3 sample:32
[17:16:38]app_bt_stream_open prev:9 cur:3 freq:0
[17:16:38]bt_sbc_player work:0 op:0 freq:0 :sample:44100
[17:16:38]
[17:16:38]bt_media_volume_ptr_update_by_mediatype enter
[17:16:38]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:38]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:16:38]bt_media_volume_ptr_update_by_mediatype exit
[17:16:38]bt_sbc_player: app_sysfreq_req 2
[17:16:38]app_overlay_select id:4:8
[17:16:38]bt_init_xtal_sync
[17:16:38][af_stream_open] id = 0, stream = 0
[17:16:38]codec_int_open: running=0
[17:16:38]trig codec open
[17:16:38]codec_hw_open
[17:16:38]codec_hw_open: codec_hw_state=1
[17:16:38]codec_int_stream_open: stream=0
[17:16:38]codec_int_stream_setup: stream=0
[17:16:38][sample_rate]old = 16000, new = 44100
[17:16:38][vol]old = 0, new = 17
[17:16:38][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:38][hal_codec_setup_stream] playback sample_rate=44100
[17:16:38]a2dp_audio_init f=42768 20720
[17:16:38]
[17:16:38]codec_int_stream_start: stream=0
[17:16:38]codec_hw_start: stream=0
[17:16:38]store_sbc_buffer not ready:436 cache_status:0
[17:16:38]store_sbc_buffer not ready:457 cache_status:0
[17:16:38]store_sbc_buffer not ready:464 cache_status:0
[17:16:38]bt_xtal_sync: ms=29023 bit_offset=525
[17:16:38]a2dp_audio_more_data cache not ready skip frame 2
[17:16:38]
[17:16:38]a2dp_audio_more_data cache not ready skip frame 2
[17:16:38]
[17:16:38]bt_xtal_sync: ms=29055 bit_offset=526
[17:16:38]a2dp_audio_more_data cache not ready skip frame 2
[17:16:38]
[17:16:38]aac cache ok:2467
[17:16:38]
[17:16:38]bt_xtal_sync: ms=29113 bit_offset=527
[17:16:38]bt_xtal_sync: ms=29148 bit_offset=528
[17:16:38]bt_xtal_sync: ms=29246 bit_offset=530
[17:16:38]bt_xtal_sync: ms=29277 bit_offset=531
[17:16:38]bt_xtal_sync: ms=29343 bit_offset=532
[17:16:38]bt_xtal_sync: ms=29375 bit_offset=533
[17:16:38]bt_xtal_sync: ms=29410 bit_offset=534
[17:16:38]bt_xtal_sync: ms=29476 bit_offset=535
[17:16:38]bt_xtal_sync: ms=29507 bit_offset=536
[17:16:39]bt_xtal_sync: ms=29542 bit_offset=537
[17:16:39]
[17:16:39]
[17:16:39]Update 26M: diff=10 ms=428 ppb=23324
[17:16:39]
[17:16:39]
[17:16:39]bt_xtal_sync: Bad ppb=23324 last_valid_ppb=-2147483648 last_tune_ppb=0 (1)
[17:16:39]bt_xtal_sync: ms=29615 bit_offset=538
[17:16:39]bt_xtal_sync: ms=29657 bit_offset=539
[17:16:39]bt_xtal_sync: ms=29712 bit_offset=540
[17:16:39]bt_xtal_sync: ms=29740 bit_offset=541
[17:16:39]bt_xtal_sync: ms=29798 bit_offset=542
[17:16:39]bt_xtal_sync: ms=29833 bit_offset=543
[17:16:39]bt_xtal_sync: ms=29864 bit_offset=544
[17:16:39]bt_xtal_sync: ms=29900 bit_offset=545
[17:16:39]bt_xtal_sync: ms=29966 bit_offset=546
[17:16:39]bt_xtal_sync: ms=30006 bit_offset=547
[17:16:39]bt_xtal_sync: ms=30069 bit_offset=548
[17:16:39]bt_xtal_sync: ms=30100 bit_offset=549
[17:16:39]bt_xtal_sync: ms=30159 bit_offset=550
[17:16:39]bt_xtal_sync: ms=30194 bit_offset=551
[17:16:39]bt_xtal_sync: ms=30249 bit_offset=552
[17:16:39]
[17:16:39]
[17:16:39]Update 26M: diff=12 ms=536 ppb=22352
[17:16:39]
[17:16:39]
[17:16:39]bt_xtal_sync: Bad ppb=22352 last_valid_ppb=23324 last_tune_ppb=0 (2)
[17:16:39]bt_xtal_sync: ms=30288 bit_offset=553
[17:16:39]bt_xtal_sync: ms=30323 bit_offset=554
[17:16:39]bt_xtal_sync: ms=30381 bit_offset=555
[17:16:39]bt_xtal_sync: ms=30424 bit_offset=556
[17:16:39]bt_xtal_sync: ms=30483 bit_offset=557
[17:16:39]bt_xtal_sync: ms=30514 bit_offset=558
[17:16:39]bt_xtal_sync: ms=30549 bit_offset=559
[17:16:40]bt_xtal_sync: ms=30618 bit_offset=561
[17:16:40]bt_xtal_sync: ms=30679 bit_offset=562
[17:16:40]bt_xtal_sync: ms=30738 bit_offset=563
[17:16:40]bt_xtal_sync: ms=30769 bit_offset=564
[17:16:40]bt_xtal_sync: ms=30863 bit_offset=566
[17:16:40]
[17:16:40]
[17:16:40]Update 26M: diff=10 ms=481 ppb=20752
[17:16:40]
[17:16:40]
[17:16:40]bt_xtal_sync: Bad ppb=20752 last_valid_ppb=22352 last_tune_ppb=0 (3)
[17:16:40]bt_xtal_sync: ms=30893 bit_offset=567
[17:16:40]bt_xtal_sync: ms=30934 bit_offset=568
[17:16:40]bt_xtal_sync: ms=31037 bit_offset=570
[17:16:40]bt_xtal_sync: ms=31103 bit_offset=571
[17:16:40]bt_xtal_sync: ms=31166 bit_offset=573
[17:16:40]bt_xtal_sync: ms=31255 bit_offset=575
[17:16:40],rssi_monitor[0]:210 ,rssi_monitor[1]: 165 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:40]
[17:16:40]bt_xtal_sync: ms=31314 bit_offset=576
[17:16:40]bt_xtal_sync: ms=31342 bit_offset=577
[17:16:40]bt_xtal_sync: ms=31385 bit_offset=578
[17:16:40]bt_xtal_sync: ms=31451 bit_offset=579
[17:16:40]bt_xtal_sync: ms=31486 bit_offset=580
[17:16:40]bt_xtal_sync: ms=31521 bit_offset=581
[17:16:40]
[17:16:40]
[17:16:40]Update 26M: diff=12 ms=484 ppb=24774
[17:16:40]
[17:16:40]
[17:16:40]bt_xtal_sync: Bad ppb=24774 last_valid_ppb=20752 last_tune_ppb=0 (4)
[17:16:41]bt_xtal_sync: ms=31580 bit_offset=582
[17:16:41]bt_xtal_sync: ms=31615 bit_offset=583
[17:16:41]bt_xtal_sync: ms=31679 bit_offset=584
[17:16:41]bt_xtal_sync: ms=31705 bit_offset=585
[17:16:41]bt_xtal_sync: ms=31791 bit_offset=587
[17:16:41]bt_xtal_sync: ms=31846 bit_offset=588
[17:16:41]bt_xtal_sync: ms=31873 bit_offset=589
[17:16:41]bt_xtal_sync: ms=31936 bit_offset=590
[17:16:41]bt_xtal_sync: ms=31963 bit_offset=591
[17:16:41]bt_xtal_sync: ms=32028 bit_offset=592
[17:16:41]bt_xtal_sync: ms=32062 bit_offset=593
[17:16:41]app_bt_key 1024,5
[17:16:41]first blood!
[17:16:41]bt_key_send code:1024 evt:5
[17:16:41]bt_key_handle code:1024 evt:5
[17:16:41]
[17:16:41]==2001=vol up ===event:5==
[17:16:41]
[17:16:41]app_bt_stream_volumeup set audio volume
[17:16:41]app_bt_stream_volumeset vol=18
[17:16:41]codec_int_stream_setup: stream=0
[17:16:41][sample_rate]old = 44100, new = 44100
[17:16:41][codec_int_stream_setup]stream = 0, set_flag = 2
[17:16:41][hal_codec_setup_stream] playback sample_rate=44100
[17:16:41]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1d
[17:16:41]bt_media_start media_active = 3,curr_active_media = 1
[17:16:41]set current media = 2
[17:16:41]app_bt_stream_close prev:3 cur:3
[17:16:41]bt_sbc_player work:1 op:1 freq:0 :sample:44100
[17:16:41]
[17:16:41]codec_int_stream_stop: stream=0
[17:16:41]app_bt_stream_volumeup a2dp: 17
[17:16:41]app_bt_stream_volumeup hfp: 12
[17:16:41]hfp get vol raw:10 loc:12
[17:16:41]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:41]
[17:16:41]codec_hw_stop: stream=0
[17:16:41]codec_int_stream_close: stream=0
[17:16:41]codec_int_close: type=0 running=1
[17:16:41]trig codec close
[17:16:41]codec_hw_close
[17:16:41]codec_hw_close: codec_hw_state=2
[17:16:41]bt_term_xtal_sync_default
[17:16:41]app_bt_stream_open prev:9 cur:7 freq:0
[17:16:41]app_play_audio_onoff work:0 op:1 aud_id:29
[17:16:41]app_overlay_select id:1:8
[17:16:41][af_stream_open] id = 0, stream = 0
[17:16:41]codec_int_open: running=0
[17:16:41]trig codec open
[17:16:41]codec_hw_open
[17:16:41]codec_hw_open: codec_hw_state=1
[17:16:41]codec_int_stream_open: stream=0
[17:16:41]codec_int_stream_setup: stream=0
[17:16:41][sample_rate]old = 44100, new = 16000
[17:16:41][vol]old = 17, new = 0
[17:16:41][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:41][hal_codec_setup_stream] playback sample_rate=16000
[17:16:41]codec_int_stream_start: stream=0
[17:16:41]codec_hw_start: stream=0
[17:16:41]channelid is 0x42, mux's channelid is 0x42
[17:16:41] [RX:]
[17:16:41]13 05 01 80 00 01 00
[17:16:41]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:41]
[17:16:42]cache underflow
[17:16:42]media_sbc_decoder underflow len:256
[17:16:42]
[17:16:42]app_play_sbc_more_data-->need close, length:2480 len:256 l:0
[17:16:42]app_bt_stream_close prev:7 cur:7
[17:16:42]app_play_audio_onoff work:1 op:0 aud_id:0
[17:16:42]codec_int_stream_stop: stream=0
[17:16:42]codec_hw_stop: stream=0
[17:16:42]codec_int_stream_close: stream=0
[17:16:42]codec_int_close: type=0 running=1
[17:16:42]trig codec close
[17:16:42]codec_hw_close
[17:16:42]codec_hw_close: codec_hw_state=2
[17:16:42]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:16:42]bt_media_stop media_active = 3,curr_active_media = 2
[17:16:42]app_audio_manager_switch_a2dp switch_a2dp to id:0
[17:16:42]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:42]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:16:42]set current media = 1
[17:16:42]bt_media_stop end media_active = 1,curr_active_media = 1
[17:16:42]app_bt_stream_setup prev:9 cur:3 sample:32
[17:16:42]app_bt_stream_open prev:9 cur:3 freq:0
[17:16:42]bt_sbc_player work:0 op:0 freq:0 :sample:44100
[17:16:42]
[17:16:42]bt_media_volume_ptr_update_by_mediatype enter
[17:16:42]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:42]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:16:42]bt_media_volume_ptr_update_by_mediatype exit
[17:16:42]bt_sbc_player: app_sysfreq_req 2
[17:16:42]app_overlay_select id:4:8
[17:16:42]bt_init_xtal_sync
[17:16:42][af_stream_open] id = 0, stream = 0
[17:16:42]codec_int_open: running=0
[17:16:42]trig codec open
[17:16:42]codec_hw_open
[17:16:42]codec_hw_open: codec_hw_state=1
[17:16:42]codec_int_stream_open: stream=0
[17:16:42]codec_int_stream_setup: stream=0
[17:16:42][sample_rate]old = 16000, new = 44100
[17:16:42][vol]old = 0, new = 17
[17:16:42][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:42][hal_codec_setup_stream] playback sample_rate=44100
[17:16:42]a2dp_audio_init f=42768 20720
[17:16:42]
[17:16:42]codec_int_stream_start: stream=0
[17:16:42]codec_hw_start: stream=0
[17:16:42]store_sbc_buffer not ready:412 cache_status:0
[17:16:42]store_sbc_buffer not ready:391 cache_status:0
[17:16:42]a2dp_audio_more_data cache not ready skip frame 2
[17:16:42]
[17:16:42]bt_xtal_sync: ms=32674 bit_offset=606
[17:16:42]a2dp_audio_more_data cache not ready skip frame 2
[17:16:42]
[17:16:42]bt_xtal_sync: ms=32709 bit_offset=607
[17:16:42]a2dp_audio_more_data cache not ready skip frame 2
[17:16:42]
[17:16:42]bt_xtal_sync: ms=32736 bit_offset=608
[17:16:42]a2dp_audio_more_data cache not ready skip frame 2
[17:16:42]
[17:16:42]aac cache ok:2391
[17:16:42]
[17:16:42]bt_xtal_sync: ms=32803 bit_offset=609
[17:16:42]bt_xtal_sync: ms=32834 bit_offset=610
[17:16:42]bt_xtal_sync: ms=32870 bit_offset=611
[17:16:42]bt_xtal_sync: ms=32936 bit_offset=612
[17:16:42]bt_xtal_sync: ms=32963 bit_offset=613
[17:16:42]bt_xtal_sync: ms=33021 bit_offset=614
[17:16:42]bt_xtal_sync: ms=33049 bit_offset=615
[17:16:42]bt_xtal_sync: ms=33108 bit_offset=616
[17:16:42]bt_xtal_sync: ms=33139 bit_offset=617
[17:16:42]bt_xtal_sync: ms=33198 bit_offset=618
[17:16:42]bt_xtal_sync: ms=33229 bit_offset=619
[17:16:42]bt_xtal_sync: ms=33268 bit_offset=620
[17:16:42]
[17:16:42]
[17:16:42]Update 26M: diff=12 ms=531 ppb=22588
[17:16:42]
[17:16:42]
[17:16:42]bt_xtal_sync: Bad ppb=22588 last_valid_ppb=-2147483648 last_tune_ppb=0 (1)
[17:16:42]bt_xtal_sync: ms=33330 bit_offset=621
[17:16:42]bt_xtal_sync: ms=33358 bit_offset=622
[17:16:42]bt_xtal_sync: ms=33423 bit_offset=623
[17:16:42]bt_xtal_sync: ms=33453 bit_offset=624
[17:16:42]bt_xtal_sync: ms=33516 bit_offset=0
[17:16:43]bt_xtal_sync: ms=33578 bit_offset=1
[17:16:43]app_bt_key 1024,5
[17:16:43]first blood!
[17:16:43]bt_key_send code:1024 evt:5
[17:16:43]bt_key_handle code:1024 evt:5
[17:16:43]
[17:16:43]==2001=vol up ===event:5==
[17:16:43]
[17:16:43]app_bt_stream_volumeup set audio volume
[17:16:43]app_bt_stream_volumeset vol=18
[17:16:43]codec_int_stream_setup: stream=0
[17:16:43][sample_rate]old = 44100, new = 44100
[17:16:43][codec_int_stream_setup]stream = 0, set_flag = 2
[17:16:43][hal_codec_setup_stream] playback sample_rate=44100
[17:16:43]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1d
[17:16:43]bt_media_start media_active = 3,curr_active_media = 1
[17:16:43]set current media = 2
[17:16:43]app_bt_stream_close prev:3 cur:3
[17:16:43]bt_sbc_player work:1 op:1 freq:0 :sample:44100
[17:16:43]
[17:16:43]app_bt_stream_volumeup a2dp: 17
[17:16:43]app_bt_stream_volumeup hfp: 12
[17:16:43]codec_int_stream_stop: stream=0
[17:16:43]hfp get vol raw:10 loc:12
[17:16:43]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:43]
[17:16:43]codec_hw_stop: stream=0
[17:16:43]codec_int_stream_close: stream=0
[17:16:43]codec_int_close: type=0 running=1
[17:16:43]trig codec close
[17:16:43]codec_hw_close
[17:16:43]codec_hw_close: codec_hw_state=2
[17:16:43]bt_term_xtal_sync_default
[17:16:43]app_bt_stream_open prev:9 cur:7 freq:0
[17:16:43]app_play_audio_onoff work:0 op:1 aud_id:29
[17:16:43]app_overlay_select id:1:8
[17:16:43][af_stream_open] id = 0, stream = 0
[17:16:43]codec_int_open: running=0
[17:16:43]trig codec open
[17:16:43]codec_hw_open
[17:16:43]codec_hw_open: codec_hw_state=1
[17:16:43]codec_int_stream_open: stream=0
[17:16:43]codec_int_stream_setup: stream=0
[17:16:43][sample_rate]old = 44100, new = 16000
[17:16:43][vol]old = 17, new = 0
[17:16:43][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:43][hal_codec_setup_stream] playback sample_rate=16000
[17:16:43]codec_int_stream_start: stream=0
[17:16:43]codec_hw_start: stream=0
[17:16:43]channelid is 0x42, mux's channelid is 0x42
[17:16:43] [RX:]
[17:16:43]13 05 01 80 00 01 00
[17:16:43]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:43]
[17:16:43]cache underflow
[17:16:43]media_sbc_decoder underflow len:256
[17:16:43]
[17:16:43]app_play_sbc_more_data-->need close, length:2480 len:256 l:0
[17:16:43]app_bt_stream_close prev:7 cur:7
[17:16:43]app_play_audio_onoff work:1 op:0 aud_id:0
[17:16:43]codec_int_stream_stop: stream=0
[17:16:43]codec_hw_stop: stream=0
[17:16:43]codec_int_stream_close: stream=0
[17:16:43]codec_int_close: type=0 running=1
[17:16:43]trig codec close
[17:16:43]codec_hw_close
[17:16:43]codec_hw_close: codec_hw_state=2
[17:16:43]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:16:43]bt_media_stop media_active = 3,curr_active_media = 2
[17:16:43]app_audio_manager_switch_a2dp switch_a2dp to id:0
[17:16:43]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:43]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:16:43]set current media = 1
[17:16:43]bt_media_stop end media_active = 1,curr_active_media = 1
[17:16:43]app_bt_stream_setup prev:9 cur:3 sample:32
[17:16:43]app_bt_stream_open prev:9 cur:3 freq:0
[17:16:43]bt_sbc_player work:0 op:0 freq:0 :sample:44100
[17:16:43]
[17:16:43]bt_media_volume_ptr_update_by_mediatype enter
[17:16:43]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:43]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:16:43]bt_media_volume_ptr_update_by_mediatype exit
[17:16:43]bt_sbc_player: app_sysfreq_req 2
[17:16:43]app_overlay_select id:4:8
[17:16:43]bt_init_xtal_sync
[17:16:43][af_stream_open] id = 0, stream = 0
[17:16:43]codec_int_open: running=0
[17:16:43]trig codec open
[17:16:43]codec_hw_open
[17:16:43]codec_hw_open: codec_hw_state=1
[17:16:43]codec_int_stream_open: stream=0
[17:16:43]codec_int_stream_setup: stream=0
[17:16:43][sample_rate]old = 16000, new = 44100
[17:16:43][vol]old = 0, new = 17
[17:16:43][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:43][hal_codec_setup_stream] playback sample_rate=44100
[17:16:43]a2dp_audio_init f=42768 20720
[17:16:43]
[17:16:43]codec_int_stream_start: stream=0
[17:16:43]codec_hw_start: stream=0
[17:16:43]store_sbc_buffer not ready:395 cache_status:0
[17:16:43]store_sbc_buffer not ready:409 cache_status:0
[17:16:43]store_sbc_buffer not ready:391 cache_status:0
[17:16:43]bt_xtal_sync: ms=34173 bit_offset=15
[17:16:43]a2dp_audio_more_data cache not ready skip frame 2
[17:16:43]
[17:16:43]a2dp_audio_more_data cache not ready skip frame 2
[17:16:43]
[17:16:43]a2dp_audio_more_data cache not ready skip frame 2
[17:16:43]
[17:16:43]a2dp_audio_more_data cache not ready skip frame 2
[17:16:43]
[17:16:43]aac cache ok:2407
[17:16:43]
[17:16:43]bt_xtal_sync: ms=34263 bit_offset=17
[17:16:43]bt_xtal_sync: ms=34368 bit_offset=19
[17:16:43]bt_xtal_sync: ms=34403 bit_offset=20
[17:16:43]bt_xtal_sync: ms=34497 bit_offset=22
[17:16:43]bt_xtal_sync: ms=34531 bit_offset=23
[17:16:44]bt_xtal_sync: ms=34637 bit_offset=25
[17:16:44]bt_xtal_sync: ms=34665 bit_offset=26
[17:16:44]bt_xtal_sync: ms=34735 bit_offset=27
[17:16:44],rssi_monitor[0]:451 ,rssi_monitor[1]: 192 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:44]
[17:16:44]bt_xtal_sync: ms=34793 bit_offset=29
[17:16:44]app_bt_key 1024,5
[17:16:44]first blood!
[17:16:44]bt_key_send code:1024 evt:5
[17:16:44]bt_key_handle code:1024 evt:5
[17:16:44]
[17:16:44]==2001=vol up ===event:5==
[17:16:44]
[17:16:44]app_bt_stream_volumeup set audio volume
[17:16:44]app_bt_stream_volumeset vol=18
[17:16:44]codec_int_stream_setup: stream=0
[17:16:44][sample_rate]old = 44100, new = 44100
[17:16:44][codec_int_stream_setup]stream = 0, set_flag = 2
[17:16:44][hal_codec_setup_stream] playback sample_rate=44100
[17:16:44]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1d
[17:16:44]bt_media_start media_active = 3,curr_active_media = 1
[17:16:44]set current media = 2
[17:16:44]app_bt_stream_close prev:3 cur:3
[17:16:44]bt_sbc_player work:1 op:1 freq:0 :sample:44100
[17:16:44]
[17:16:44]app_bt_stream_volumeup a2dp: 17
[17:16:44]app_bt_stream_volumeup hfp: 12
[17:16:44]hfp get vol raw:10 loc:12
[17:16:44]codec_int_stream_stop: stream=0
[17:16:44]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:44]
[17:16:44]codec_hw_stop: stream=0
[17:16:44]codec_int_stream_close: stream=0
[17:16:44]codec_int_close: type=0 running=1
[17:16:44]trig codec close
[17:16:44]codec_hw_close
[17:16:44]codec_hw_close: codec_hw_state=2
[17:16:44]bt_term_xtal_sync_default
[17:16:44]app_bt_stream_open prev:9 cur:7 freq:0
[17:16:44]app_play_audio_onoff work:0 op:1 aud_id:29
[17:16:44]app_overlay_select id:1:8
[17:16:44][af_stream_open] id = 0, stream = 0
[17:16:44]codec_int_open: running=0
[17:16:44]trig codec open
[17:16:44]codec_hw_open
[17:16:44]codec_hw_open: codec_hw_state=1
[17:16:44]codec_int_stream_open: stream=0
[17:16:44]codec_int_stream_setup: stream=0
[17:16:44][sample_rate]old = 44100, new = 16000
[17:16:44][vol]old = 17, new = 0
[17:16:44][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:44][hal_codec_setup_stream] playback sample_rate=16000
[17:16:44]codec_int_stream_start: stream=0
[17:16:44]codec_hw_start: stream=0
[17:16:44]channelid is 0x42, mux's channelid is 0x42
[17:16:44] [RX:]
[17:16:44]13 05 01 80 00 01 00
[17:16:44]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:44]
[17:16:44]cache underflow
[17:16:44]media_sbc_decoder underflow len:256
[17:16:44]
[17:16:44]app_play_sbc_more_data-->need close, length:2480 len:256 l:0
[17:16:44]app_bt_stream_close prev:7 cur:7
[17:16:44]app_play_audio_onoff work:1 op:0 aud_id:0
[17:16:44]codec_int_stream_stop: stream=0
[17:16:44]codec_hw_stop: stream=0
[17:16:44]codec_int_stream_close: stream=0
[17:16:44]codec_int_close: type=0 running=1
[17:16:44]trig codec close
[17:16:44]codec_hw_close
[17:16:44]codec_hw_close: codec_hw_state=2
[17:16:44]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:16:44]bt_media_stop media_active = 3,curr_active_media = 2
[17:16:44]app_audio_manager_switch_a2dp switch_a2dp to id:0
[17:16:44]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:44]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:16:44]set current media = 1
[17:16:44]bt_media_stop end media_active = 1,curr_active_media = 1
[17:16:44]app_bt_stream_setup prev:9 cur:3 sample:32
[17:16:44]app_bt_stream_open prev:9 cur:3 freq:0
[17:16:44]bt_sbc_player work:0 op:0 freq:0 :sample:44100
[17:16:44]
[17:16:44]bt_media_volume_ptr_update_by_mediatype enter
[17:16:44]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:44]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:16:44]bt_media_volume_ptr_update_by_mediatype exit
[17:16:44]bt_sbc_player: app_sysfreq_req 2
[17:16:44]app_overlay_select id:4:8
[17:16:44]bt_init_xtal_sync
[17:16:44][af_stream_open] id = 0, stream = 0
[17:16:44]codec_int_open: running=0
[17:16:44]trig codec open
[17:16:44]codec_hw_open
[17:16:44]codec_hw_open: codec_hw_state=1
[17:16:44]codec_int_stream_open: stream=0
[17:16:44]codec_int_stream_setup: stream=0
[17:16:44][sample_rate]old = 16000, new = 44100
[17:16:44][vol]old = 0, new = 17
[17:16:44][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:44][hal_codec_setup_stream] playback sample_rate=44100
[17:16:44]a2dp_audio_init f=42768 20720
[17:16:44]
[17:16:44]codec_int_stream_start: stream=0
[17:16:44]codec_hw_start: stream=0
[17:16:44]store_sbc_buffer not ready:447 cache_status:0
[17:16:44]store_sbc_buffer not ready:439 cache_status:0
[17:16:44]store_sbc_buffer not ready:411 cache_status:0
[17:16:44]bt_xtal_sync: ms=35451 bit_offset=43
[17:16:44]a2dp_audio_more_data cache not ready skip frame 2
[17:16:44]
[17:16:44]bt_xtal_sync: ms=35482 bit_offset=44
[17:16:44]a2dp_audio_more_data cache not ready skip frame 2
[17:16:44]
[17:16:44]a2dp_audio_more_data cache not ready skip frame 2
[17:16:44]
[17:16:44]aac cache ok:2150
[17:16:44]
[17:16:44]bt_xtal_sync: ms=35536 bit_offset=45
[17:16:44]bt_xtal_sync: ms=35565 bit_offset=46
[17:16:45]CPU USAGE: busy=56 light_sleep=44 deep_sleep=0
[17:16:45]bt_xtal_sync: ms=35627 bit_offset=47
[17:16:45]bt_xtal_sync: ms=35682 bit_offset=48
[17:16:45]bt_xtal_sync: ms=35713 bit_offset=49
[17:16:45]bt_xtal_sync: ms=35772 bit_offset=50
[17:16:45]bt_xtal_sync: ms=35800 bit_offset=51
[17:16:45]bt_xtal_sync: ms=35861 bit_offset=52
[17:16:45]bt_xtal_sync: ms=35890 bit_offset=53
[17:16:45]bt_xtal_sync: ms=35952 bit_offset=54
[17:16:45]app_bt_key 1024,5
[17:16:45]first blood!
[17:16:45]bt_key_send code:1024 evt:5
[17:16:45]bt_key_handle code:1024 evt:5
[17:16:45]
[17:16:45]==2001=vol up ===event:5==
[17:16:45]
[17:16:45]app_bt_stream_volumeup set audio volume
[17:16:45]app_bt_stream_volumeset vol=18
[17:16:45]codec_int_stream_setup: stream=0
[17:16:45][sample_rate]old = 44100, new = 44100
[17:16:45][codec_int_stream_setup]stream = 0, set_flag = 2
[17:16:45][hal_codec_setup_stream] playback sample_rate=44100
[17:16:45]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1d
[17:16:45]bt_media_start media_active = 3,curr_active_media = 1
[17:16:45]set current media = 2
[17:16:45]app_bt_stream_close prev:3 cur:3
[17:16:45]bt_sbc_player work:1 op:1 freq:0 :sample:44100
[17:16:45]
[17:16:45]app_bt_stream_volumeup a2dp: 17
[17:16:45]app_bt_stream_volumeup hfp: 12
[17:16:45]hfp get vol raw:10 loc:12
[17:16:45]codec_int_stream_stop: stream=0
[17:16:45]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:45]
[17:16:45]codec_hw_stop: stream=0
[17:16:45]codec_int_stream_close: stream=0
[17:16:45]codec_int_close: type=0 running=1
[17:16:45]trig codec close
[17:16:45]codec_hw_close
[17:16:45]codec_hw_close: codec_hw_state=2
[17:16:45]bt_term_xtal_sync_default
[17:16:45]app_bt_stream_open prev:9 cur:7 freq:0
[17:16:45]app_play_audio_onoff work:0 op:1 aud_id:29
[17:16:45]app_overlay_select id:1:8
[17:16:45][af_stream_open] id = 0, stream = 0
[17:16:45]codec_int_open: running=0
[17:16:45]trig codec open
[17:16:45]codec_hw_open
[17:16:45]codec_hw_open: codec_hw_state=1
[17:16:45]codec_int_stream_open: stream=0
[17:16:45]codec_int_stream_setup: stream=0
[17:16:45][sample_rate]old = 44100, new = 16000
[17:16:45][vol]old = 17, new = 0
[17:16:45][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:45][hal_codec_setup_stream] playback sample_rate=16000
[17:16:45]codec_int_stream_start: stream=0
[17:16:45]codec_hw_start: stream=0
[17:16:45]channelid is 0x42, mux's channelid is 0x42
[17:16:45] [RX:]
[17:16:45]13 05 01 80 00 01 00
[17:16:45]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:45]
[17:16:45]cache underflow
[17:16:45]media_sbc_decoder underflow len:256
[17:16:45]
[17:16:45]app_play_sbc_more_data-->need close, length:2480 len:256 l:0
[17:16:45]app_bt_stream_close prev:7 cur:7
[17:16:45]app_play_audio_onoff work:1 op:0 aud_id:0
[17:16:45]codec_int_stream_stop: stream=0
[17:16:45]codec_hw_stop: stream=0
[17:16:45]codec_int_stream_close: stream=0
[17:16:45]codec_int_close: type=0 running=1
[17:16:45]trig codec close
[17:16:45]codec_hw_close
[17:16:45]codec_hw_close: codec_hw_state=2
[17:16:45]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:16:45]bt_media_stop media_active = 3,curr_active_media = 2
[17:16:45]app_audio_manager_switch_a2dp switch_a2dp to id:0
[17:16:45]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:45]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:16:45]set current media = 1
[17:16:45]bt_media_stop end media_active = 1,curr_active_media = 1
[17:16:45]app_bt_stream_setup prev:9 cur:3 sample:32
[17:16:45]app_bt_stream_open prev:9 cur:3 freq:0
[17:16:45]bt_sbc_player work:0 op:0 freq:0 :sample:44100
[17:16:45]
[17:16:45]bt_media_volume_ptr_update_by_mediatype enter
[17:16:45]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:45]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:16:45]bt_media_volume_ptr_update_by_mediatype exit
[17:16:45]bt_sbc_player: app_sysfreq_req 2
[17:16:45]app_overlay_select id:4:8
[17:16:45]bt_init_xtal_sync
[17:16:45][af_stream_open] id = 0, stream = 0
[17:16:45]codec_int_open: running=0
[17:16:45]trig codec open
[17:16:45]codec_hw_open
[17:16:45]codec_hw_open: codec_hw_state=1
[17:16:45]codec_int_stream_open: stream=0
[17:16:45]codec_int_stream_setup: stream=0
[17:16:45][sample_rate]old = 16000, new = 44100
[17:16:45][vol]old = 0, new = 17
[17:16:45][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:45][hal_codec_setup_stream] playback sample_rate=44100
[17:16:45]a2dp_audio_init f=42768 20720
[17:16:45]
[17:16:45]codec_int_stream_start: stream=0
[17:16:45]codec_hw_start: stream=0
[17:16:45]store_sbc_buffer not ready:405 cache_status:0
[17:16:45]store_sbc_buffer not ready:404 cache_status:0
[17:16:46]a2dp_audio_more_data cache not ready skip frame 2
[17:16:46]
[17:16:46]a2dp_audio_more_data cache not ready skip frame 2
[17:16:46]
[17:16:46]bt_xtal_sync: ms=36602 bit_offset=69
[17:16:46]a2dp_audio_more_data cache not ready skip frame 2
[17:16:46]
[17:16:46]aac cache ok:2075
[17:16:46]
[17:16:46]bt_xtal_sync: ms=36700 bit_offset=71
[17:16:46]bt_xtal_sync: ms=36785 bit_offset=73
[17:16:46]bt_xtal_sync: ms=36885 bit_offset=75
[17:16:46]bt_xtal_sync: ms=36920 bit_offset=76
[17:16:46]bt_xtal_sync: ms=37010 bit_offset=78
[17:16:46]bt_xtal_sync: ms=37048 bit_offset=79
[17:16:46]bt_xtal_sync: ms=37107 bit_offset=80
[17:16:46]bt_xtal_sync: ms=37146 bit_offset=81
[17:16:46]bt_xtal_sync: ms=37211 bit_offset=82
[17:16:46]bt_xtal_sync: ms=37295 bit_offset=84
[17:16:46]bt_xtal_sync: ms=37341 bit_offset=85
[17:16:46]
[17:16:46]
[17:16:46]Update 26M: diff=12 ms=555 ppb=21597
[17:16:46]
[17:16:46]
[17:16:46]bt_xtal_sync: Bad ppb=21597 last_valid_ppb=-2147483648 last_tune_ppb=0 (1)
[17:16:46]bt_xtal_sync: ms=37372 bit_offset=86
[17:16:46]bt_xtal_sync: ms=37470 bit_offset=88
[17:16:46]bt_xtal_sync: ms=37505 bit_offset=89
[17:16:47]bt_xtal_sync: ms=37597 bit_offset=91
[17:16:47]bt_xtal_sync: ms=37680 bit_offset=92
[17:16:47]bt_xtal_sync: ms=37718 bit_offset=93
[17:16:47]bt_xtal_sync: ms=37746 bit_offset=94
[17:16:47]bt_xtal_sync: ms=37805 bit_offset=95
[17:16:47]bt_xtal_sync: ms=37851 bit_offset=96
[17:16:47]bt_xtal_sync: ms=37914 bit_offset=98
[17:16:47]bt_xtal_sync: ms=37948 bit_offset=99
[17:16:47]bt_xtal_sync: ms=38046 bit_offset=101
[17:16:47]
[17:16:47]
[17:16:47]Update 26M: diff=12 ms=541 ppb=22171
[17:16:47]
[17:16:47]
[17:16:47]bt_xtal_sync: Bad ppb=22171 last_valid_ppb=21597 last_tune_ppb=0 (2)
[17:16:47]bt_xtal_sync: ms=38143 bit_offset=103
[17:16:47]bt_xtal_sync: ms=38233 bit_offset=105
[17:16:47],rssi_monitor[0]:227 ,rssi_monitor[1]: 178 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:47]
[17:16:47]bt_xtal_sync: ms=38303 bit_offset=106
[17:16:47]bt_xtal_sync: ms=38342 bit_offset=107
[17:16:47]bt_xtal_sync: ms=38411 bit_offset=109
[17:16:47]bt_xtal_sync: ms=38508 bit_offset=111
[17:16:47]bt_xtal_sync: ms=38543 bit_offset=112
[17:16:48]app_bt_key 2048,5
[17:16:48]first blood!
[17:16:48]bt_key_send code:2048 evt:5
[17:16:48]bt_key_handle code:2048 evt:5
[17:16:48]
[17:16:48]==2001==vol down====event:5=
[17:16:48]
[17:16:48]app_bt_stream_volumedown set audio volume
[17:16:48]app_bt_stream_volumeset vol=16
[17:16:48]codec_int_stream_setup: stream=0
[17:16:48][sample_rate]old = 44100, new = 44100
[17:16:48][vol]old = 17, new = 16
[17:16:48][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:48][hal_codec_setup_stream] playback sample_rate=44100
[17:16:48]app_bt_stream_volumedown a2dp: 16
[17:16:48]app_bt_stream_volumedown hfp: 12
[17:16:48]hfp get vol raw:10 loc:12
[17:16:48]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:48]
[17:16:48]channelid is 0x42, mux's channelid is 0x42
[17:16:48]bt_xtal_sync: ms=38603 bit_offset=113
[17:16:48] [RX:]
[17:16:48]13 05 01 80 00 01 00
[17:16:48]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:48]
[17:16:48]bt_xtal_sync: ms=38666 bit_offset=114
[17:16:48]bt_xtal_sync: ms=38694 bit_offset=115
[17:16:48]bt_xtal_sync: ms=38720 bit_offset=116
[17:16:48]bt_xtal_sync: ms=38773 bit_offset=117
[17:16:48]
[17:16:48]
[17:16:48]Update 26M: diff=12 ms=469 ppb=25566
[17:16:48]
[17:16:48]
[17:16:48]bt_xtal_sync: Bad ppb=25566 last_valid_ppb=22171 last_tune_ppb=0 (3)
[17:16:48]bt_xtal_sync: ms=38832 bit_offset=118
[17:16:48]bt_xtal_sync: ms=38860 bit_offset=119
[17:16:48]bt_xtal_sync: ms=38918 bit_offset=120
[17:16:48]bt_xtal_sync: ms=38973 bit_offset=121
[17:16:48]bt_xtal_sync: ms=39001 bit_offset=122
[17:16:48]bt_xtal_sync: ms=39063 bit_offset=123
[17:16:48]bt_xtal_sync: ms=39095 bit_offset=124
[17:16:48]bt_xtal_sync: ms=39155 bit_offset=125
[17:16:48]bt_xtal_sync: ms=39193 bit_offset=126
[17:16:48]bt_xtal_sync: ms=39225 bit_offset=127
[17:16:48]bt_xtal_sync: ms=39280 bit_offset=128
[17:16:48]bt_xtal_sync: ms=39307 bit_offset=129
[17:16:48]bt_xtal_sync: ms=39366 bit_offset=130
[17:16:48]bt_xtal_sync: ms=39421 bit_offset=131
[17:16:48]bt_xtal_sync: ms=39452 bit_offset=132
[17:16:48]
[17:16:48]
[17:16:48]Update 26M: diff=12 ms=533 ppb=22482
[17:16:48]
[17:16:48]
[17:16:48]bt_xtal_sync: Bad ppb=22482 last_valid_ppb=25566 last_tune_ppb=0 (4)
[17:16:48]bt_xtal_sync: ms=39507 bit_offset=133
[17:16:48]bt_xtal_sync: ms=39535 bit_offset=134
[17:16:49]app_bt_key 2048,5
[17:16:49]first blood!
[17:16:49]bt_key_send code:2048 evt:5
[17:16:49]bt_key_handle code:2048 evt:5
[17:16:49]
[17:16:49]==2001==vol down====event:5=
[17:16:49]
[17:16:49]app_bt_stream_volumedown set audio volume
[17:16:49]app_bt_stream_volumeset vol=15
[17:16:49]codec_int_stream_setup: stream=0
[17:16:49][sample_rate]old = 44100, new = 44100
[17:16:49][vol]old = 16, new = 15
[17:16:49][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:49][hal_codec_setup_stream] playback sample_rate=44100
[17:16:49]app_bt_stream_volumedown a2dp: 15
[17:16:49]app_bt_stream_volumedown hfp: 12
[17:16:49]hfp get vol raw:10 loc:12
[17:16:49]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:49]
[17:16:49]channelid is 0x42, mux's channelid is 0x42
[17:16:49]bt_xtal_sync: ms=39593 bit_offset=135
[17:16:49] [RX:]
[17:16:49]13 05 01 80 00 01 00
[17:16:49]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:49]
[17:16:49]bt_xtal_sync: ms=39653 bit_offset=136
[17:16:49]bt_xtal_sync: ms=39696 bit_offset=137
[17:16:49]bt_xtal_sync: ms=39731 bit_offset=138
[17:16:49]bt_xtal_sync: ms=39790 bit_offset=139
[17:16:49]bt_xtal_sync: ms=39821 bit_offset=140
[17:16:49]bt_xtal_sync: ms=39853 bit_offset=141
[17:16:49]bt_xtal_sync: ms=39903 bit_offset=142
[17:16:49]bt_xtal_sync: ms=39995 bit_offset=144
[17:16:49]bt_xtal_sync: ms=40082 bit_offset=146
[17:16:49]
[17:16:49]
[17:16:49]Update 26M: diff=10 ms=488 ppb=20460
[17:16:49]
[17:16:49]
[17:16:49]bt_xtal_sync: Bad ppb=20460 last_valid_ppb=22482 last_tune_ppb=0 (5)
[17:16:49]bt_xtal_sync: ms=40165 bit_offset=147
[17:16:49]bt_xtal_sync: ms=40223 bit_offset=149
[17:16:49]bt_xtal_sync: ms=40258 bit_offset=150
[17:16:49]bt_xtal_sync: ms=40352 bit_offset=152
[17:16:49]bt_xtal_sync: ms=40438 bit_offset=154
[17:16:49]bt_xtal_sync: ms=40496 bit_offset=155
[17:16:49]bt_xtal_sync: ms=40586 bit_offset=157
[17:16:50],rssi_monitor[0]:210 ,rssi_monitor[1]: 165 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:50]
[17:16:50]bt_xtal_sync: ms=40617 bit_offset=158
[17:16:50]bt_xtal_sync: ms=40687 bit_offset=159
[17:16:50]
[17:16:50]
[17:16:50]Update 26M: diff=10 ms=428 ppb=23324
[17:16:50]
[17:16:50]
[17:16:50]bt_xtal_sync: Bad ppb=23324 last_valid_ppb=20460 last_tune_ppb=0 (6)
[17:16:50]bt_reset_xtal_to_default: Reset xtal to default 45
[17:16:50]bt_xtal_sync: ms=40722 bit_offset=160
[17:16:50]app_bt_accessmode_timehandler accMode=2
[17:16:50]app_bt_mail_process src_thread:0x20003768 request_id:5 enter
[17:16:50]!!!ME_SetAccessibleMode MEC(accOp).mode = 2, mode = 2
[17:16:50]
[17:16:50]app_bt_mail_process request_id:5 :status:2 exit
[17:16:50]bt_xtal_sync: ms=40759 bit_offset=161
[17:16:50] [RX:]
[17:16:50]0e 04 05 1a 0c 00
[17:16:50]app_bt_golbal_handle evt = 10
[17:16:50]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x2
[17:16:50]bt_xtal_sync: ms=40825 bit_offset=162
[17:16:50]bt_xtal_sync: ms=40860 bit_offset=163
[17:16:50]bt_xtal_sync: ms=40887 bit_offset=164
[17:16:50]*** SYSFREQ user=3 freq=2
[17:16:50]*** SYSFREQ top_user=3
[17:16:50]app_battery_handle_process 0,3692
[17:16:50]checker: id:0 state:3 mode:0 role:1 cmghdl:2000d700 sniffInterva:0/800 IsAudioUp:0
[17:16:50]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:16:50]remDev:2000e3e8 a2dp State:3 remDev:2000e3e8 hf_channel Connected:1 remDev:2000e3e8
[17:16:50]bt_xtal_sync: ms=40946 bit_offset=165
[17:16:50]bt_xtal_sync: ms=41001 bit_offset=166
[17:16:50]bt_xtal_sync: ms=41032 bit_offset=167
[17:16:50]bt_xtal_sync: ms=41087 bit_offset=168
[17:16:50]bt_xtal_sync: ms=41115 bit_offset=169
[17:16:50]bt_xtal_sync: ms=41173 bit_offset=170
[17:16:50]bt_xtal_sync: ms=41263 bit_offset=172
[17:16:50]bt_xtal_sync: ms=41298 bit_offset=173
[17:16:50]bt_xtal_sync: ms=41361 bit_offset=174
[17:16:50]bt_xtal_sync: ms=41388 bit_offset=175
[17:16:50]
[17:16:50]
[17:16:50]Update 26M: diff=13 ms=563 ppb=23060
[17:16:50]
[17:16:50]
[17:16:50]bt_xtal_sync: Bad ppb=23060 last_valid_ppb=23324 last_tune_ppb=0 (1)
[17:16:50]bt_xtal_sync: ms=41455 bit_offset=176
[17:16:50]bt_xtal_sync: ms=41482 bit_offset=177
[17:16:50]bt_xtal_sync: ms=41525 bit_offset=178
[17:16:51]bt_xtal_sync: ms=41591 bit_offset=179
[17:16:51]bt_xtal_sync: ms=41622 bit_offset=180
[17:16:51]bt_xtal_sync: ms=41713 bit_offset=182
[17:16:51]bt_xtal_sync: ms=41800 bit_offset=184
[17:16:51]bt_xtal_sync: ms=41855 bit_offset=185
[17:16:51]bt_xtal_sync: ms=41890 bit_offset=186
[17:16:51]bt_xtal_sync: ms=41948 bit_offset=187
[17:16:51]bt_xtal_sync: ms=41976 bit_offset=188
[17:16:51]bt_xtal_sync: ms=42042 bit_offset=189
[17:16:51]
[17:16:51]
[17:16:51]Update 26M: diff=11 ms=517 ppb=21256
[17:16:51]
[17:16:51]
[17:16:51]bt_xtal_sync: Bad ppb=21256 last_valid_ppb=23060 last_tune_ppb=0 (2)
[17:16:51]bt_xtal_sync: ms=42100 bit_offset=190
[17:16:51]bt_xtal_sync: ms=42135 bit_offset=191
[17:16:51]bt_xtal_sync: ms=42193 bit_offset=192
[17:16:51]bt_xtal_sync: ms=42248 bit_offset=194
[17:16:51]bt_xtal_sync: ms=42342 bit_offset=196
[17:16:51]bt_xtal_sync: ms=42432 bit_offset=198
[17:16:51]bt_xtal_sync: ms=42467 bit_offset=199
[17:16:51]app_bt_key 2048,5
[17:16:51]first blood!
[17:16:51]bt_key_send code:2048 evt:5
[17:16:51]bt_key_handle code:2048 evt:5
[17:16:51]
[17:16:51]==2001==vol down====event:5=
[17:16:51]
[17:16:51]app_bt_stream_volumedown set audio volume
[17:16:51]app_bt_stream_volumeset vol=14
[17:16:51]codec_int_stream_setup: stream=0
[17:16:51][sample_rate]old = 44100, new = 44100
[17:16:51][vol]old = 15, new = 14
[17:16:51][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:51][hal_codec_setup_stream] playback sample_rate=44100
[17:16:51]app_bt_stream_volumedown a2dp: 14
[17:16:51]app_bt_stream_volumedown hfp: 12
[17:16:51]hfp get vol raw:10 loc:12
[17:16:51]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:51]
[17:16:51]channelid is 0x42, mux's channelid is 0x42
[17:16:51] [RX:]
[17:16:51]13 05 01 80 00 01 00
[17:16:51]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:51]
[17:16:52]bt_xtal_sync: ms=42566 bit_offset=201
[17:16:52]bt_xtal_sync: ms=42621 bit_offset=202
[17:16:52]bt_xtal_sync: ms=42660 bit_offset=203
[17:16:52]bt_xtal_sync: ms=42715 bit_offset=204
[17:16:52]bt_xtal_sync: ms=42742 bit_offset=205
[17:16:52]
[17:16:52]
[17:16:52]Update 26M: diff=13 ms=548 ppb=23690
[17:16:52]
[17:16:52]
[17:16:52]bt_xtal_sync: Bad ppb=23690 last_valid_ppb=21256 last_tune_ppb=0 (3)
[17:16:52]bt_xtal_sync: ms=42801 bit_offset=206
[17:16:52]bt_xtal_sync: ms=42828 bit_offset=207
[17:16:52]bt_xtal_sync: ms=42892 bit_offset=208
[17:16:52],rssi_monitor[0]:210 ,rssi_monitor[1]: 165 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:52]
[17:16:52]bt_xtal_sync: ms=42927 bit_offset=209
[17:16:52]bt_xtal_sync: ms=43013 bit_offset=211
[17:16:52]bt_xtal_sync: ms=43111 bit_offset=213
[17:16:52]bt_xtal_sync: ms=43201 bit_offset=215
[17:16:52]bt_xtal_sync: ms=43286 bit_offset=217
[17:16:52]bt_xtal_sync: ms=43342 bit_offset=218
[17:16:52]bt_xtal_sync: ms=43385 bit_offset=219
[17:16:52]bt_xtal_sync: ms=43478 bit_offset=221
[17:16:52]bt_xtal_sync: ms=43510 bit_offset=222
[17:16:52]
[17:16:52]
[17:16:52]Update 26M: diff=13 ms=617 ppb=21053
[17:16:52]
[17:16:52]
[17:16:52]bt_xtal_sync: Bad ppb=21053 last_valid_ppb=23690 last_tune_ppb=0 (4)
[17:16:53]bt_xtal_sync: ms=43576 bit_offset=223
[17:16:53]bt_xtal_sync: ms=43615 bit_offset=224
[17:16:53]bt_xtal_sync: ms=43685 bit_offset=226
[17:16:53]bt_xtal_sync: ms=43771 bit_offset=228
[17:16:53]bt_xtal_sync: ms=43865 bit_offset=230
[17:16:53]bt_xtal_sync: ms=43967 bit_offset=232
[17:16:53]bt_xtal_sync: ms=44057 bit_offset=234
[17:16:53]bt_xtal_sync: ms=44092 bit_offset=235
[17:16:53]bt_xtal_sync: ms=44178 bit_offset=237
[17:16:53]
[17:16:53]
[17:16:53]Update 26M: diff=11 ms=493 ppb=22278
[17:16:53]
[17:16:53]
[17:16:53]bt_xtal_sync: Bad ppb=22278 last_valid_ppb=21053 last_tune_ppb=0 (5)
[17:16:53]bt_xtal_sync: ms=44261 bit_offset=238
[17:16:53]bt_xtal_sync: ms=44319 bit_offset=240
[17:16:53]bt_xtal_sync: ms=44386 bit_offset=241
[17:16:53]bt_xtal_sync: ms=44447 bit_offset=243
[17:16:53]bt_xtal_sync: ms=44530 bit_offset=244
[17:16:54]bt_xtal_sync: ms=44571 bit_offset=245
[17:16:54]bt_xtal_sync: ms=44606 bit_offset=246
[17:16:54]bt_xtal_sync: ms=44667 bit_offset=247
[17:16:54]bt_xtal_sync: ms=44699 bit_offset=248
[17:16:54]bt_xtal_sync: ms=44725 bit_offset=249
[17:16:54]bt_xtal_sync: ms=44778 bit_offset=250
[17:16:54]bt_xtal_sync: ms=44838 bit_offset=251
[17:16:54]
[17:16:54]
[17:16:54]Update 26M: diff=11 ms=451 ppb=24343
[17:16:54]
[17:16:54]
[17:16:54]bt_xtal_sync: Bad ppb=24343 last_valid_ppb=22278 last_tune_ppb=0 (6)
[17:16:54]bt_reset_xtal_to_default: Reset xtal to default 45
[17:16:54]bt_xtal_sync: ms=44870 bit_offset=252
[17:16:54]bt_xtal_sync: ms=44932 bit_offset=253
[17:16:54]bt_xtal_sync: ms=44960 bit_offset=254
[17:16:54]bt_xtal_sync: ms=45018 bit_offset=255
[17:16:54]bt_xtal_sync: ms=45076 bit_offset=256
[17:16:54]bt_xtal_sync: ms=45111 bit_offset=257
[17:16:54]bt_xtal_sync: ms=45142 bit_offset=258
[17:16:54]bt_xtal_sync: ms=45205 bit_offset=259
[17:16:54],rssi_monitor[0]:178 ,rssi_monitor[1]: 163 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:54]
[17:16:54]bt_xtal_sync: ms=45240 bit_offset=260
[17:16:54]bt_xtal_sync: ms=45306 bit_offset=262
[17:16:54]bt_xtal_sync: ms=45372 bit_offset=263
[17:16:54]bt_xtal_sync: ms=45438 bit_offset=264
[17:16:54]
[17:16:54]
[17:16:54]Update 26M: diff=10 ms=478 ppb=20888
[17:16:54]
[17:16:54]
[17:16:54]bt_xtal_sync: Bad ppb=20888 last_valid_ppb=24343 last_tune_ppb=0 (1)
[17:16:54]bt_xtal_sync: ms=45470 bit_offset=265
[17:16:54]bt_xtal_sync: ms=45505 bit_offset=266
[17:16:55]bt_xtal_sync: ms=45576 bit_offset=267
[17:16:55]CPU USAGE: busy=47 light_sleep=53 deep_sleep=0
[17:16:55]bt_xtal_sync: ms=45607 bit_offset=268
[17:16:55]bt_xtal_sync: ms=45662 bit_offset=269
[17:16:55]bt_xtal_sync: ms=45691 bit_offset=270
[17:16:55]bt_xtal_sync: ms=45767 bit_offset=272
[17:16:55]bt_xtal_sync: ms=45831 bit_offset=273
[17:16:55]bt_xtal_sync: ms=45896 bit_offset=274
[17:16:55]bt_xtal_sync: ms=45942 bit_offset=275
[17:16:55]bt_xtal_sync: ms=45992 bit_offset=277
[17:16:55]bt_xtal_sync: ms=46062 bit_offset=278
[17:16:55]
[17:16:55]
[17:16:55]Update 26M: diff=10 ms=486 ppb=20566
[17:16:55]
[17:16:55]
[17:16:55]bt_xtal_sync: Bad ppb=20566 last_valid_ppb=20888 last_tune_ppb=0 (2)
[17:16:55]bt_xtal_sync: ms=46125 bit_offset=280
[17:16:55]bt_xtal_sync: ms=46211 bit_offset=282
[17:16:55]bt_xtal_sync: ms=46297 bit_offset=283
[17:16:55]bt_xtal_sync: ms=46363 bit_offset=285
[17:16:55]bt_xtal_sync: ms=46395 bit_offset=286
[17:16:55]bt_xtal_sync: ms=46450 bit_offset=287
[17:16:55]bt_xtal_sync: ms=46505 bit_offset=288
[17:16:55]bt_xtal_sync: ms=46547 bit_offset=289
[17:16:56]bt_xtal_sync: ms=46645 bit_offset=291
[17:16:56]bt_xtal_sync: ms=46680 bit_offset=292
[17:16:56]bt_xtal_sync: ms=46735 bit_offset=293
[17:16:56]app_bt_accessmode_timehandler accMode=3
[17:16:56]app_bt_mail_process src_thread:0x20003768 request_id:5 enter
[17:16:56]!!!ME_SetAccessibleMode MEC(accOp).mode = 3, mode = 3
[17:16:56]
[17:16:56]app_bt_mail_process request_id:5 :status:2 exit
[17:16:56] [RX:]
[17:16:56]0e 04 05 1a 0c 00
[17:16:56]bt_xtal_sync: ms=46777 bit_offset=294
[17:16:56]
[17:16:56]
[17:16:56]Update 26M: diff=12 ms=480 ppb=25000
[17:16:56]
[17:16:56]
[17:16:56]bt_xtal_sync: Bad ppb=25000 last_valid_ppb=20566 last_tune_ppb=0 (3)
[17:16:56]app_bt_golbal_handle evt = 10
[17:16:56]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x3
[17:16:56]bt_xtal_sync: ms=46850 bit_offset=295
[17:16:56]bt_xtal_sync: ms=46900 bit_offset=297
[17:16:56]bt_xtal_sync: ms=46962 bit_offset=298
[17:16:56]bt_xtal_sync: ms=47028 bit_offset=300
[17:16:56]bt_xtal_sync: ms=47133 bit_offset=302
[17:16:56]bt_xtal_sync: ms=47165 bit_offset=303
[17:16:56]bt_xtal_sync: ms=47220 bit_offset=304
[17:16:56]bt_xtal_sync: ms=47293 bit_offset=305
[17:16:56]bt_xtal_sync: ms=47352 bit_offset=307
[17:16:56]bt_xtal_sync: ms=47435 bit_offset=308
[17:16:56]bt_xtal_sync: ms=47494 bit_offset=310
[17:16:57],rssi_monitor[0]:771 ,rssi_monitor[1]: 212 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:57]
[17:16:57]bt_xtal_sync: ms=47583 bit_offset=312
[17:16:57]bt_xtal_sync: ms=47666 bit_offset=314
[17:16:57]bt_xtal_sync: ms=47728 bit_offset=315
[17:16:57]bt_xtal_sync: ms=47763 bit_offset=316
[17:16:57]bt_xtal_sync: ms=47850 bit_offset=318
[17:16:57]bt_xtal_sync: ms=47962 bit_offset=320
[17:16:57]bt_xtal_sync: ms=47990 bit_offset=321
[17:16:57]bt_xtal_sync: ms=48056 bit_offset=322
[17:16:57]bt_xtal_sync: ms=48128 bit_offset=324
[17:16:57]bt_xtal_sync: ms=48186 bit_offset=325
[17:16:57]bt_xtal_sync: ms=48221 bit_offset=326
[17:16:57]bt_xtal_sync: ms=48280 bit_offset=327
[17:16:57]bt_xtal_sync: ms=48326 bit_offset=328
[17:16:57]
[17:16:57]
[17:16:57]Update 26M: diff=12 ms=562 ppb=21333
[17:16:57]
[17:16:57]
[17:16:57]bt_xtal_sync: Bad ppb=21333 last_valid_ppb=25000 last_tune_ppb=0 (4)
[17:16:57]bt_xtal_sync: ms=48353 bit_offset=329
[17:16:57]bt_xtal_sync: ms=48408 bit_offset=330
[17:16:57]bt_xtal_sync: ms=48451 bit_offset=331
[17:16:57]bt_xtal_sync: ms=48490 bit_offset=332
[17:16:57]bt_xtal_sync: ms=48552 bit_offset=333
[17:16:58]bt_xtal_sync: ms=48583 bit_offset=334
[17:16:58]bt_xtal_sync: ms=48642 bit_offset=335
[17:16:58]bt_xtal_sync: ms=48681 bit_offset=336
[17:16:58]bt_xtal_sync: ms=48743 bit_offset=337
[17:16:58]bt_xtal_sync: ms=48771 bit_offset=338
[17:16:58]bt_xtal_sync: ms=48841 bit_offset=339
[17:16:58]bt_xtal_sync: ms=48868 bit_offset=340
[17:16:58]bt_xtal_sync: ms=48915 bit_offset=341
[17:16:58]bt_xtal_sync: ms=48973 bit_offset=342
[17:16:58]bt_xtal_sync: ms=49008 bit_offset=343
[17:16:58]
[17:16:58]
[17:16:58]Update 26M: diff=12 ms=557 ppb=21525
[17:16:58]
[17:16:58]
[17:16:58]bt_xtal_sync: Bad ppb=21525 last_valid_ppb=21333 last_tune_ppb=0 (5)
[17:16:58]bt_xtal_sync: ms=49075 bit_offset=345
[17:16:58]bt_xtal_sync: ms=49168 bit_offset=347
[17:16:58]bt_xtal_sync: ms=49262 bit_offset=349
[17:16:58]app_bt_key 1024,5
[17:16:58]first blood!
[17:16:58]bt_key_send code:1024 evt:5
[17:16:58]bt_key_handle code:1024 evt:5
[17:16:58]
[17:16:58]==2001=vol up ===event:5==
[17:16:58]
[17:16:58]app_bt_stream_volumeup set audio volume
[17:16:58]app_bt_stream_volumeset vol=15
[17:16:58]codec_int_stream_setup: stream=0
[17:16:58][sample_rate]old = 44100, new = 44100
[17:16:58][vol]old = 14, new = 15
[17:16:58][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:58][hal_codec_setup_stream] playback sample_rate=44100
[17:16:58]app_bt_stream_volumeup a2dp: 15
[17:16:58]app_bt_stream_volumeup hfp: 12
[17:16:58]hfp get vol raw:10 loc:12
[17:16:58]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:58]
[17:16:58]channelid is 0x42, mux's channelid is 0x42
[17:16:58]bt_xtal_sync: ms=49353 bit_offset=351
[17:16:58]bt_xtal_sync: ms=49409 bit_offset=352
[17:16:58] [RX:]
[17:16:58]13 05 01 80 00 01 00
[17:16:58]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:58]
[17:16:58]bt_xtal_sync: ms=49460 bit_offset=353
[17:16:58]bt_xtal_sync: ms=49526 bit_offset=355
[17:16:59]bt_xtal_sync: ms=49627 bit_offset=357
[17:16:59]bt_xtal_sync: ms=49721 bit_offset=359
[17:16:59]
[17:16:59]
[17:16:59]Update 26M: diff=10 ms=458 ppb=21798
[17:16:59]
[17:16:59]
[17:16:59]bt_xtal_sync: Bad ppb=21798 last_valid_ppb=21525 last_tune_ppb=0 (6)
[17:16:59]bt_reset_xtal_to_default: Reset xtal to default 45
[17:16:59]bt_xtal_sync: ms=49752 bit_offset=360
[17:16:59]bt_xtal_sync: ms=49811 bit_offset=361
[17:16:59]bt_xtal_sync: ms=49842 bit_offset=362
[17:16:59],rssi_monitor[0]:258 ,rssi_monitor[1]: 168 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:16:59]
[17:16:59]bt_xtal_sync: ms=49905 bit_offset=363
[17:16:59]bt_xtal_sync: ms=49998 bit_offset=365
[17:16:59]bt_xtal_sync: ms=50026 bit_offset=366
[17:16:59]bt_xtal_sync: ms=50138 bit_offset=368
[17:16:59]bt_xtal_sync: ms=50170 bit_offset=369
[17:16:59]bt_xtal_sync: ms=50228 bit_offset=370
[17:16:59]bt_xtal_sync: ms=50267 bit_offset=371
[17:16:59]bt_xtal_sync: ms=50302 bit_offset=372
[17:16:59]bt_xtal_sync: ms=50368 bit_offset=373
[17:16:59]
[17:16:59]
[17:16:59]Update 26M: diff=11 ms=526 ppb=20903
[17:16:59]
[17:16:59]
[17:16:59]bt_xtal_sync: Bad ppb=20903 last_valid_ppb=21798 last_tune_ppb=0 (1)
[17:16:59]bt_xtal_sync: ms=50396 bit_offset=374
[17:16:59]bt_xtal_sync: ms=50458 bit_offset=375
[17:16:59]app_bt_key 1024,5
[17:16:59]first blood!
[17:16:59]bt_key_send code:1024 evt:5
[17:16:59]bt_key_handle code:1024 evt:5
[17:16:59]
[17:16:59]==2001=vol up ===event:5==
[17:16:59]
[17:16:59]app_bt_stream_volumeup set audio volume
[17:16:59]app_bt_stream_volumeset vol=16
[17:16:59]codec_int_stream_setup: stream=0
[17:16:59][sample_rate]old = 44100, new = 44100
[17:16:59][vol]old = 15, new = 16
[17:16:59][codec_int_stream_setup]stream = 0, set_flag = 12
[17:16:59][hal_codec_setup_stream] playback sample_rate=44100
[17:16:59]app_bt_stream_volumeup a2dp: 16
[17:16:59]app_bt_stream_volumeup hfp: 12
[17:16:59]hfp get vol raw:10 loc:12
[17:16:59]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:16:59]
[17:16:59]channelid is 0x42, mux's channelid is 0x42
[17:16:59] [RX:]
[17:16:59]13 05 01 80 00 01 00
[17:16:59]bt_xtal_sync: ms=50497 bit_offset=376
[17:16:59]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:16:59]
[17:17:00]bt_xtal_sync: ms=50566 bit_offset=377
[17:17:00]bt_xtal_sync: ms=50605 bit_offset=378
[17:17:00]bt_xtal_sync: ms=50690 bit_offset=380
[17:17:00]bt_xtal_sync: ms=50725 bit_offset=381
[17:17:00]bt_xtal_sync: ms=50756 bit_offset=382
[17:17:00]bt_xtal_sync: ms=50830 bit_offset=383
[17:17:00]bt_xtal_sync: ms=50861 bit_offset=384
[17:17:00]bt_xtal_sync: ms=50946 bit_offset=386
[17:17:00]bt_xtal_sync: ms=51040 bit_offset=388
[17:17:00]
[17:17:00]
[17:17:00]Update 26M: diff=11 ms=542 ppb=20276
[17:17:00]
[17:17:00]
[17:17:00]bt_xtal_sync: Bad ppb=20276 last_valid_ppb=20903 last_tune_ppb=0 (2)
[17:17:00]bt_xtal_sync: ms=51125 bit_offset=390
[17:17:00]bt_xtal_sync: ms=51184 bit_offset=391
[17:17:00]bt_xtal_sync: ms=51238 bit_offset=392
[17:17:00]bt_xtal_sync: ms=51266 bit_offset=393
[17:17:00]bt_xtal_sync: ms=51332 bit_offset=394
[17:17:00]bt_xtal_sync: ms=51371 bit_offset=395
[17:17:00]bt_xtal_sync: ms=51426 bit_offset=396
[17:17:00]app_bt_key 1024,5
[17:17:00]first blood!
[17:17:00]bt_key_send code:1024 evt:5
[17:17:00]bt_key_handle code:1024 evt:5
[17:17:00]
[17:17:00]==2001=vol up ===event:5==
[17:17:00]
[17:17:00]app_bt_stream_volumeup set audio volume
[17:17:00]app_bt_stream_volumeset vol=17
[17:17:00]codec_int_stream_setup: stream=0
[17:17:00][sample_rate]old = 44100, new = 44100
[17:17:00][vol]old = 16, new = 17
[17:17:00][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:00][hal_codec_setup_stream] playback sample_rate=44100
[17:17:00]app_bt_stream_volumeup a2dp: 17
[17:17:00]app_bt_stream_volumeup hfp: 12
[17:17:00]hfp get vol raw:10 loc:12
[17:17:00]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:00]
[17:17:00]channelid is 0x42, mux's channelid is 0x42
[17:17:00] [RX:]
[17:17:00]13 05 01 80 00 01 00
[17:17:00]bt_xtal_sync: ms=51496 bit_offset=398
[17:17:00]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:00]
[17:17:00]bt_xtal_sync: ms=51528 bit_offset=399
[17:17:01]bt_xtal_sync: ms=51583 bit_offset=400
[17:17:01]bt_xtal_sync: ms=51688 bit_offset=402
[17:17:01]bt_xtal_sync: ms=51743 bit_offset=403
[17:17:01]
[17:17:01]
[17:17:01]Update 26M: diff=10 ms=505 ppb=19802
[17:17:01]
[17:17:01]
[17:17:01]bt_tune_xtal: BAD new_val=144 init_val=45 tune_factor=200
[17:17:01]
[17:17:01]
[17:17:01]tune_ppb=0
[17:17:01]
[17:17:01]
[17:17:01]bt_xtal_sync: ms=51771 bit_offset=404
[17:17:01]bt_xtal_sync: ms=51837 bit_offset=405
[17:17:01]bt_xtal_sync: ms=51865 bit_offset=406
[17:17:01]bt_xtal_sync: ms=51972 bit_offset=408
[17:17:01]bt_xtal_sync: ms=52047 bit_offset=410
[17:17:01]bt_xtal_sync: ms=52075 bit_offset=411
[17:17:01]bt_xtal_sync: ms=52141 bit_offset=412
[17:17:01]bt_xtal_sync: ms=52176 bit_offset=413
[17:17:01],rssi_monitor[0]:211 ,rssi_monitor[1]: 177 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:01]
[17:17:01]bt_xtal_sync: ms=52273 bit_offset=415
[17:17:01]app_bt_key 1024,5
[17:17:01]first blood!
[17:17:01]bt_key_send code:1024 evt:5
[17:17:01]bt_key_handle code:1024 evt:5
[17:17:01]
[17:17:01]==2001=vol up ===event:5==
[17:17:01]
[17:17:01]app_bt_stream_volumeup set audio volume
[17:17:01]app_bt_stream_volumeset vol=18
[17:17:01]codec_int_stream_setup: stream=0
[17:17:01][sample_rate]old = 44100, new = 44100
[17:17:01][codec_int_stream_setup]stream = 0, set_flag = 2
[17:17:01][hal_codec_setup_stream] playback sample_rate=44100
[17:17:01]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1d
[17:17:01]bt_media_start media_active = 3,curr_active_media = 1
[17:17:01]set current media = 2
[17:17:01]app_bt_stream_close prev:3 cur:3
[17:17:01]bt_sbc_player work:1 op:1 freq:0 :sample:44100
[17:17:01]
[17:17:01]app_bt_stream_volumeup a2dp: 17
[17:17:01]app_bt_stream_volumeup hfp: 12
[17:17:01]hfp get vol raw:10 loc:12
[17:17:01]codec_int_stream_stop: stream=0
[17:17:01]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:01]
[17:17:01]codec_hw_stop: stream=0
[17:17:01]codec_int_stream_close: stream=0
[17:17:01]codec_int_close: type=0 running=1
[17:17:01]trig codec close
[17:17:01]codec_hw_close
[17:17:01]codec_hw_close: codec_hw_state=2
[17:17:01]bt_term_xtal_sync_default
[17:17:01]app_bt_stream_open prev:9 cur:7 freq:0
[17:17:01]app_play_audio_onoff work:0 op:1 aud_id:29
[17:17:01]app_overlay_select id:1:8
[17:17:01][af_stream_open] id = 0, stream = 0
[17:17:01]codec_int_open: running=0
[17:17:01]trig codec open
[17:17:01]codec_hw_open
[17:17:01]codec_hw_open: codec_hw_state=1
[17:17:01]codec_int_stream_open: stream=0
[17:17:01]codec_int_stream_setup: stream=0
[17:17:01][sample_rate]old = 44100, new = 16000
[17:17:01][vol]old = 17, new = 0
[17:17:01][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:01][hal_codec_setup_stream] playback sample_rate=16000
[17:17:01]codec_int_stream_start: stream=0
[17:17:01]codec_hw_start: stream=0
[17:17:01]channelid is 0x42, mux's channelid is 0x42
[17:17:01] [RX:]
[17:17:01]13 05 01 80 00 01 00
[17:17:01]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:01]
[17:17:02]cache underflow
[17:17:02]media_sbc_decoder underflow len:256
[17:17:02]
[17:17:02]app_play_sbc_more_data-->need close, length:2480 len:256 l:0
[17:17:02]app_bt_stream_close prev:7 cur:7
[17:17:02]app_play_audio_onoff work:1 op:0 aud_id:0
[17:17:02]codec_int_stream_stop: stream=0
[17:17:02]codec_hw_stop: stream=0
[17:17:02]codec_int_stream_close: stream=0
[17:17:02]codec_int_close: type=0 running=1
[17:17:02]trig codec close
[17:17:02]codec_hw_close
[17:17:02]codec_hw_close: codec_hw_state=2
[17:17:02]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:17:02]bt_media_stop media_active = 3,curr_active_media = 2
[17:17:02]app_audio_manager_switch_a2dp switch_a2dp to id:0
[17:17:02]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:17:02]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:17:02]set current media = 1
[17:17:02]bt_media_stop end media_active = 1,curr_active_media = 1
[17:17:02]app_bt_stream_setup prev:9 cur:3 sample:32
[17:17:02]app_bt_stream_open prev:9 cur:3 freq:0
[17:17:02]bt_sbc_player work:0 op:0 freq:0 :sample:44100
[17:17:02]
[17:17:02]bt_media_volume_ptr_update_by_mediatype enter
[17:17:02]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:17:02]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:17:02]bt_media_volume_ptr_update_by_mediatype exit
[17:17:02]bt_sbc_player: app_sysfreq_req 2
[17:17:02]app_overlay_select id:4:8
[17:17:02]bt_init_xtal_sync
[17:17:02][af_stream_open] id = 0, stream = 0
[17:17:02]codec_int_open: running=0
[17:17:02]trig codec open
[17:17:02]codec_hw_open
[17:17:02]codec_hw_open: codec_hw_state=1
[17:17:02]codec_int_stream_open: stream=0
[17:17:02]codec_int_stream_setup: stream=0
[17:17:02][sample_rate]old = 16000, new = 44100
[17:17:02][vol]old = 0, new = 17
[17:17:02][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:02][hal_codec_setup_stream] playback sample_rate=44100
[17:17:02]a2dp_audio_init f=42768 20720
[17:17:02]
[17:17:02]codec_int_stream_start: stream=0
[17:17:02]codec_hw_start: stream=0
[17:17:02]store_sbc_buffer not ready:462 cache_status:0
[17:17:02]store_sbc_buffer not ready:467 cache_status:0
[17:17:02]store_sbc_buffer not ready:454 cache_status:0
[17:17:02]store_sbc_buffer not ready:442 cache_status:0
[17:17:02]bt_xtal_sync: ms=52868 bit_offset=428
[17:17:02]a2dp_audio_more_data cache not ready skip frame 2
[17:17:02]
[17:17:02]aac cache ok:2276
[17:17:02]
[17:17:02]bt_xtal_sync: ms=52907 bit_offset=429
[17:17:02]bt_xtal_sync: ms=52970 bit_offset=430
[17:17:02]bt_xtal_sync: ms=52997 bit_offset=431
[17:17:02]bt_xtal_sync: ms=53060 bit_offset=432
[17:17:02]bt_xtal_sync: ms=53122 bit_offset=434
[17:17:02]bt_xtal_sync: ms=53185 bit_offset=435
[17:17:02]bt_xtal_sync: ms=53250 bit_offset=436
[17:17:02]bt_xtal_sync: ms=53338 bit_offset=438
[17:17:02]bt_xtal_sync: ms=53366 bit_offset=439
[17:17:02]bt_xtal_sync: ms=53425 bit_offset=440
[17:17:02]bt_xtal_sync: ms=53456 bit_offset=441
[17:17:02]
[17:17:02]
[17:17:02]Update 26M: diff=11 ms=486 ppb=22622
[17:17:02]
[17:17:02]
[17:17:02]bt_xtal_sync: Bad ppb=22622 last_valid_ppb=-2147483648 last_tune_ppb=0 (1)
[17:17:02]bt_xtal_sync: ms=53518 bit_offset=442
[17:17:02]bt_xtal_sync: ms=53557 bit_offset=443
[17:17:03]bt_xtal_sync: ms=53627 bit_offset=445
[17:17:03]bt_xtal_sync: ms=53721 bit_offset=447
[17:17:03]bt_xtal_sync: ms=53788 bit_offset=448
[17:17:03]bt_xtal_sync: ms=53831 bit_offset=449
[17:17:03]bt_xtal_sync: ms=53865 bit_offset=450
[17:17:03]bt_xtal_sync: ms=53923 bit_offset=451
[17:17:03]hal_pwrkey_irqhandler: 00000001
[17:17:03]
[17:17:03]====pwr key (1,1,0)=(943389)==
[17:17:03]
[17:17:03]bt_xtal_sync: ms=53955 bit_offset=452
[17:17:03]bt_xtal_sync: ms=54013 bit_offset=453
[17:17:03]bt_xtal_sync: ms=54111 bit_offset=455
[17:17:03]bt_xtal_sync: ms=54142 bit_offset=456
[17:17:03]
[17:17:03]
[17:17:03]Update 26M: diff=11 ms=515 ppb=21359
[17:17:03]
[17:17:03]
[17:17:03]bt_xtal_sync: Bad ppb=21359 last_valid_ppb=22622 last_tune_ppb=0 (2)
[17:17:03]bt_xtal_sync: ms=54208 bit_offset=457
[17:17:03]bt_xtal_sync: ms=54236 bit_offset=458
[17:17:03]bt_xtal_sync: ms=54298 bit_offset=459
[17:17:03]bt_xtal_sync: ms=54326 bit_offset=460
[17:17:03]bt_xtal_sync: ms=54385 bit_offset=461
[17:17:03]bt_xtal_sync: ms=54412 bit_offset=462
[17:17:03]app_bt_key 1,5
[17:17:03]first blood!
[17:17:03]bt_key_send code:1 evt:5
[17:17:03]bt_key_handle code:1 evt:5
[17:17:03]
[17:17:03]==powerkey==play pause func=====
[17:17:03]
[17:17:03]avrcp_key = AVRCP_KEY_PAUSE
[17:17:03]bt_xtal_sync: ms=54466 bit_offset=463
[17:17:03]bt_xtal_sync: ms=54528 bit_offset=464
[17:17:04]bt_xtal_sync: ms=54570 bit_offset=465
[17:17:04]bt_xtal_sync: ms=54633 bit_offset=467
[17:17:04]bt_xtal_sync: ms=54668 bit_offset=468
[17:17:04]bt_xtal_sync: ms=54726 bit_offset=469
[17:17:04]
[17:17:04]
[17:17:04]Update 26M: diff=10 ms=427 ppb=23392
[17:17:04]
[17:17:04]
[17:17:04]bt_xtal_sync: Bad ppb=23392 last_valid_ppb=21359 last_tune_ppb=0 (3)
[17:17:04]bt_xtal_sync: ms=54816 bit_offset=471
[17:17:04]bt_xtal_sync: ms=54855 bit_offset=472
[17:17:04]hal_pwrkey_irqhandler: 00000001
[17:17:04]
[17:17:04]====pwr key (1,1,0)=(958283)==
[17:17:04]
[17:17:04]bt_xtal_sync: ms=54921 bit_offset=473
[17:17:04]bt_xtal_sync: ms=54948 bit_offset=474
[17:17:04]bt_xtal_sync: ms=55003 bit_offset=475
[17:17:04]bt_xtal_sync: ms=55070 bit_offset=476
[17:17:04],rssi_monitor[0]:195 ,rssi_monitor[1]: 176 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:04]
[17:17:04]bt_xtal_sync: ms=55105 bit_offset=477
[17:17:04]bt_xtal_sync: ms=55136 bit_offset=478
[17:17:04]bt_xtal_sync: ms=55198 bit_offset=479
[17:17:04]bt_xtal_sync: ms=55237 bit_offset=480
[17:17:04]bt_xtal_sync: ms=55300 bit_offset=481
[17:17:04]bt_xtal_sync: ms=55327 bit_offset=482
[17:17:04]app_bt_key 1,5
[17:17:04]first blood!
[17:17:04]bt_key_send code:1 evt:5
[17:17:04]bt_key_handle code:1 evt:5
[17:17:04]
[17:17:04]==powerkey==play pause func=====
[17:17:04]
[17:17:04]avrcp_key = AVRCP_KEY_PLAY
[17:17:04]bt_xtal_sync: ms=55393 bit_offset=484
[17:17:04]bt_xtal_sync: ms=55460 bit_offset=485
[17:17:04]bt_xtal_sync: ms=55526 bit_offset=487
[17:17:04]
[17:17:04]
[17:17:04]Update 26M: diff=13 ms=605 ppb=21488
[17:17:04]
[17:17:04]
[17:17:04]bt_xtal_sync: Bad ppb=21488 last_valid_ppb=23392 last_tune_ppb=0 (4)
[17:17:05]bt_xtal_sync: ms=55592 bit_offset=488
[17:17:05]CPU USAGE: busy=52 light_sleep=48 deep_sleep=0
[17:17:05]bt_xtal_sync: ms=55651 bit_offset=489
[17:17:05]*** SYSFREQ user=3 freq=2
[17:17:05]*** SYSFREQ top_user=3
[17:17:05]app_battery_handle_process 0,3685
[17:17:05]checker: id:0 state:3 mode:0 role:1 cmghdl:2000d700 sniffInterva:0/800 IsAudioUp:0
[17:17:05]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:17:05]remDev:2000e3e8 a2dp State:3 remDev:2000e3e8 hf_channel Connected:1 remDev:2000e3e8
[17:17:05]bt_xtal_sync: ms=55686 bit_offset=490
[17:17:05]bt_xtal_sync: ms=55741 bit_offset=491
[17:17:05]hal_pwrkey_irqhandler: 00000001
[17:17:05]
[17:17:05]====pwr key (1,1,0)=(972752)==
[17:17:05]
[17:17:05]bt_xtal_sync: ms=55817 bit_offset=493
[17:17:05]bt_xtal_sync: ms=55860 bit_offset=494
[17:17:05]bt_xtal_sync: ms=55906 bit_offset=495
[17:17:05]bt_xtal_sync: ms=55972 bit_offset=496
[17:17:05]bt_xtal_sync: ms=56007 bit_offset=497
[17:17:05]bt_xtal_sync: ms=56073 bit_offset=498
[17:17:05]bt_xtal_sync: ms=56105 bit_offset=499
[17:17:05]bt_xtal_sync: ms=56160 bit_offset=500
[17:17:05]
[17:17:05]
[17:17:05]Update 26M: diff=10 ms=474 ppb=21080
[17:17:05]
[17:17:05]
[17:17:05]bt_xtal_sync: Bad ppb=21080 last_valid_ppb=21488 last_tune_ppb=0 (5)
[17:17:05]bt_xtal_sync: ms=56198 bit_offset=501
[17:17:05]bt_xtal_sync: ms=56253 bit_offset=502
[17:17:05]app_bt_key 1,5
[17:17:05]first blood!
[17:17:05]bt_key_send code:1 evt:5
[17:17:05]bt_key_handle code:1 evt:5
[17:17:05]
[17:17:05]==powerkey==play pause func=====
[17:17:05]
[17:17:05]avrcp_key = AVRCP_KEY_PAUSE
[17:17:05]bt_xtal_sync: ms=56296 bit_offset=503
[17:17:05]bt_xtal_sync: ms=56327 bit_offset=504
[17:17:05]bt_xtal_sync: ms=56390 bit_offset=506
[17:17:05]bt_xtal_sync: ms=56487 bit_offset=508
[17:17:06]bt_xtal_sync: ms=56577 bit_offset=510
[17:17:06]bt_xtal_sync: ms=56667 bit_offset=512
[17:17:06]bt_xtal_sync: ms=56768 bit_offset=514
[17:17:06]
[17:17:06]
[17:17:06]Update 26M: diff=10 ms=472 ppb=21164
[17:17:06]
[17:17:06]
[17:17:06]bt_xtal_sync: Bad ppb=21164 last_valid_ppb=21080 last_tune_ppb=0 (6)
[17:17:06]bt_reset_xtal_to_default: Reset xtal to default 45
[17:17:06]bt_xtal_sync: ms=56827 bit_offset=515
[17:17:06]hal_pwrkey_irqhandler: 00000001
[17:17:06]
[17:17:06]====pwr key (1,1,0)=(990601)==
[17:17:06]
[17:17:06]bt_xtal_sync: ms=56905 bit_offset=517
[17:17:06]bt_xtal_sync: ms=56999 bit_offset=519
[17:17:06]bt_xtal_sync: ms=57034 bit_offset=520
[17:17:06]bt_xtal_sync: ms=57091 bit_offset=521
[17:17:06]bt_xtal_sync: ms=57161 bit_offset=522
[17:17:06]bt_xtal_sync: ms=57220 bit_offset=524
[17:17:06]bt_xtal_sync: ms=57321 bit_offset=525
[17:17:06]bt_xtal_sync: ms=57350 bit_offset=526
[17:17:06]app_bt_key 1,5
[17:17:06]first blood!
[17:17:06]bt_key_send code:1 evt:5
[17:17:06]bt_key_handle code:1 evt:5
[17:17:06]
[17:17:06]==powerkey==play pause func=====
[17:17:06]
[17:17:06]avrcp_key = AVRCP_KEY_PLAY
[17:17:06]bt_xtal_sync: ms=57413 bit_offset=528
[17:17:06],rssi_monitor[0]:210 ,rssi_monitor[1]: 165 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:06]
[17:17:06]bt_xtal_sync: ms=57441 bit_offset=529
[17:17:06]bt_xtal_sync: ms=57507 bit_offset=530
[17:17:06]
[17:17:06]
[17:17:06]Update 26M: diff=12 ms=508 ppb=23616
[17:17:06]
[17:17:06]
[17:17:06]bt_xtal_sync: Bad ppb=23616 last_valid_ppb=21164 last_tune_ppb=0 (1)
[17:17:06]bt_xtal_sync: ms=57543 bit_offset=531
[17:17:06]bt_xtal_sync: ms=57578 bit_offset=532
[17:17:07]bt_xtal_sync: ms=57651 bit_offset=533
[17:17:07]bt_xtal_sync: ms=57713 bit_offset=534
[17:17:07]bt_xtal_sync: ms=57741 bit_offset=535
[17:17:07]bt_xtal_sync: ms=57807 bit_offset=536
[17:17:07]bt_xtal_sync: ms=57850 bit_offset=537
[17:17:07]bt_xtal_sync: ms=57900 bit_offset=539
[17:17:07]hal_pwrkey_irqhandler: 00000001
[17:17:07]
[17:17:07]====pwr key (1,1,0)=(1007339)==
[17:17:07]
[17:17:07]bt_xtal_sync: ms=57962 bit_offset=540
[17:17:07]bt_xtal_sync: ms=58032 bit_offset=541
[17:17:07]bt_xtal_sync: ms=58067 bit_offset=542
[17:17:07]bt_xtal_sync: ms=58130 bit_offset=544
[17:17:07]
[17:17:07]
[17:17:07]Update 26M: diff=10 ms=478 ppb=20888
[17:17:07]
[17:17:07]
[17:17:07]bt_xtal_sync: Bad ppb=20888 last_valid_ppb=23616 last_tune_ppb=0 (2)
[17:17:07]bt_xtal_sync: ms=58223 bit_offset=546
[17:17:07]bt_xtal_sync: ms=58263 bit_offset=547
[17:17:07]bt_xtal_sync: ms=58370 bit_offset=549
[17:17:07]bt_xtal_sync: ms=58408 bit_offset=550
[17:17:07]app_bt_key 1,5
[17:17:07]first blood!
[17:17:07]bt_key_send code:1 evt:5
[17:17:07]bt_key_handle code:1 evt:5
[17:17:07]
[17:17:07]==powerkey==play pause func=====
[17:17:07]
[17:17:07]avrcp_key = AVRCP_KEY_PAUSE
[17:17:07]bt_xtal_sync: ms=58498 bit_offset=552
[17:17:07]bt_xtal_sync: ms=58553 bit_offset=553
[17:17:08]bt_xtal_sync: ms=58596 bit_offset=554
[17:17:08]bt_xtal_sync: ms=58683 bit_offset=556
[17:17:08]bt_xtal_sync: ms=58717 bit_offset=557
[17:17:08]bt_xtal_sync: ms=58776 bit_offset=558
[17:17:08]bt_xtal_sync: ms=58842 bit_offset=559
[17:17:08]bt_xtal_sync: ms=58888 bit_offset=560
[17:17:08]
[17:17:08]
[17:17:08]Update 26M: diff=12 ms=518 ppb=23133
[17:17:08]
[17:17:08]
[17:17:08]bt_xtal_sync: Bad ppb=23133 last_valid_ppb=20888 last_tune_ppb=0 (3)
[17:17:08]bt_xtal_sync: ms=58948 bit_offset=562
[17:17:08]bt_xtal_sync: ms=59005 bit_offset=563
[17:17:08]bt_xtal_sync: ms=59036 bit_offset=564
[17:17:08]bt_xtal_sync: ms=59091 bit_offset=565
[17:17:08]bt_xtal_sync: ms=59188 bit_offset=567
[17:17:08]bt_xtal_sync: ms=59216 bit_offset=568
[17:17:08]bt_xtal_sync: ms=59301 bit_offset=569
[17:17:08]bt_xtal_sync: ms=59332 bit_offset=570
[17:17:08]bt_xtal_sync: ms=59410 bit_offset=572
[17:17:08]bt_xtal_sync: ms=59500 bit_offset=574
[17:17:09]bt_xtal_sync: ms=59590 bit_offset=576
[17:17:09]hal_pwrkey_irqhandler: 00000001
[17:17:09]
[17:17:09]====pwr key (1,1,0)=(1034850)==
[17:17:09]
[17:17:09]bt_xtal_sync: ms=59655 bit_offset=577
[17:17:09]
[17:17:09]
[17:17:09]Update 26M: diff=12 ms=619 ppb=19374
[17:17:09]
[17:17:09]
[17:17:09]bt_xtal_sync: Bad ppb=19374 last_valid_ppb=23133 last_tune_ppb=0 (4)
[17:17:09]bt_xtal_sync: ms=59690 bit_offset=578
[17:17:09],rssi_monitor[0]:658 ,rssi_monitor[1]: 193 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:09]
[17:17:09]bt_xtal_sync: ms=59784 bit_offset=580
[17:17:09]bt_xtal_sync: ms=59811 bit_offset=581
[17:17:09]bt_xtal_sync: ms=59888 bit_offset=582
[17:17:09]bt_xtal_sync: ms=59916 bit_offset=583
[17:17:09]bt_xtal_sync: ms=59972 bit_offset=584
[17:17:09]bt_xtal_sync: ms=60003 bit_offset=585
[17:17:09]bt_xtal_sync: ms=60058 bit_offset=586
[17:17:09]bt_xtal_sync: ms=60093 bit_offset=587
[17:17:09]app_bt_key 1,5
[17:17:09]first blood!
[17:17:09]bt_key_send code:1 evt:5
[17:17:09]bt_key_handle code:1 evt:5
[17:17:09]
[17:17:09]==powerkey==play pause func=====
[17:17:09]
[17:17:09]avrcp_key = AVRCP_KEY_PLAY
[17:17:09]bt_xtal_sync: ms=60163 bit_offset=588
[17:17:09]bt_xtal_sync: ms=60194 bit_offset=589
[17:17:09]bt_xtal_sync: ms=60229 bit_offset=590
[17:17:09]
[17:17:09]
[17:17:09]Update 26M: diff=10 ms=418 ppb=23916
[17:17:09]
[17:17:09]
[17:17:09]bt_xtal_sync: Bad ppb=23916 last_valid_ppb=19374 last_tune_ppb=0 (5)
[17:17:09]bt_xtal_sync: ms=60288 bit_offset=591
[17:17:09]bt_xtal_sync: ms=60315 bit_offset=592
[17:17:09]bt_xtal_sync: ms=60374 bit_offset=593
[17:17:09]bt_xtal_sync: ms=60433 bit_offset=594
[17:17:09]bt_xtal_sync: ms=60460 bit_offset=595
[17:17:09]hal_pwrkey_irqhandler: 00000001
[17:17:09]
[17:17:09]====pwr key (1,1,0)=(1048752)==
[17:17:09]
[17:17:09]bt_xtal_sync: ms=60530 bit_offset=596
[17:17:10]bt_xtal_sync: ms=60561 bit_offset=597
[17:17:10]bt_xtal_sync: ms=60635 bit_offset=599
[17:17:10]bt_xtal_sync: ms=60677 bit_offset=600
[17:17:10]bt_xtal_sync: ms=60732 bit_offset=601
[17:17:10]bt_xtal_sync: ms=60798 bit_offset=602
[17:17:10]bt_xtal_sync: ms=60826 bit_offset=603
[17:17:10]
[17:17:10]
[17:17:10]Update 26M: diff=10 ms=451 ppb=22130
[17:17:10]
[17:17:10]
[17:17:10]bt_xtal_sync: Bad ppb=22130 last_valid_ppb=23916 last_tune_ppb=0 (6)
[17:17:10]bt_reset_xtal_to_default: Reset xtal to default 45
[17:17:10]bt_xtal_sync: ms=60927 bit_offset=605
[17:17:10]bt_xtal_sync: ms=60986 bit_offset=606
[17:17:10]bt_xtal_sync: ms=61013 bit_offset=607
[17:17:10]app_bt_key 1,5
[17:17:10]first blood!
[17:17:10]bt_key_send code:1 evt:5
[17:17:10]bt_key_handle code:1 evt:5
[17:17:10]
[17:17:10]==powerkey==play pause func=====
[17:17:10]
[17:17:10]avrcp_key = AVRCP_KEY_PAUSE
[17:17:10]bt_xtal_sync: ms=61075 bit_offset=608
[17:17:10]bt_xtal_sync: ms=61145 bit_offset=610
[17:17:10]bt_xtal_sync: ms=61238 bit_offset=612
[17:17:10]bt_xtal_sync: ms=61270 bit_offset=613
[17:17:10]bt_xtal_sync: ms=61340 bit_offset=614
[17:17:10]bt_xtal_sync: ms=61371 bit_offset=615
[17:17:10]hal_pwrkey_irqhandler: 00000001
[17:17:10]
[17:17:10]====pwr key (1,1,0)=(1063192)==
[17:17:10]
[17:17:10]bt_xtal_sync: ms=61425 bit_offset=616
[17:17:10]bt_xtal_sync: ms=61460 bit_offset=617
[17:17:10]bt_xtal_sync: ms=61523 bit_offset=618
[17:17:10]
[17:17:10]
[17:17:10]Update 26M: diff=11 ms=510 ppb=21569
[17:17:10]
[17:17:10]
[17:17:10]bt_xtal_sync: Bad ppb=21569 last_valid_ppb=22130 last_tune_ppb=0 (1)
[17:17:10]bt_xtal_sync: ms=61550 bit_offset=619
[17:17:11]bt_xtal_sync: ms=61612 bit_offset=620
[17:17:11]bt_xtal_sync: ms=61641 bit_offset=621
[17:17:11]bt_xtal_sync: ms=61700 bit_offset=622
[17:17:11]bt_xtal_sync: ms=61755 bit_offset=623
[17:17:11]bt_xtal_sync: ms=61786 bit_offset=624
[17:17:11]bt_xtal_sync: ms=61821 bit_offset=0
[17:17:11]bt_xtal_sync: ms=61876 bit_offset=1
[17:17:11]bt_xtal_sync: ms=61915 bit_offset=2
[17:17:11]app_bt_key 1,5
[17:17:11]first blood!
[17:17:11]bt_key_send code:1 evt:5
[17:17:11]bt_key_handle code:1 evt:5
[17:17:11]
[17:17:11]==powerkey==play pause func=====
[17:17:11]
[17:17:11]avrcp_key = AVRCP_KEY_PLAY
[17:17:11]bt_xtal_sync: ms=61970 bit_offset=3
[17:17:11]bt_xtal_sync: ms=62011 bit_offset=4
[17:17:11],rssi_monitor[0]:418 ,rssi_monitor[1]: 178 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:11]
[17:17:11]bt_xtal_sync: ms=62075 bit_offset=5
[17:17:11]bt_xtal_sync: ms=62102 bit_offset=6
[17:17:11]bt_xtal_sync: ms=62136 bit_offset=7
[17:17:11]bt_xtal_sync: ms=62212 bit_offset=8
[17:17:11]
[17:17:11]
[17:17:11]Update 26M: diff=12 ms=571 ppb=21007
[17:17:11]
[17:17:11]
[17:17:11]bt_xtal_sync: Bad ppb=21007 last_valid_ppb=21569 last_tune_ppb=0 (2)
[17:17:11]bt_xtal_sync: ms=62278 bit_offset=10
[17:17:11]bt_xtal_sync: ms=62368 bit_offset=12
[17:17:11]bt_xtal_sync: ms=62462 bit_offset=14
[17:17:11]bt_xtal_sync: ms=62528 bit_offset=15
[17:17:12]bt_xtal_sync: ms=62591 bit_offset=16
[17:17:12]bt_xtal_sync: ms=62634 bit_offset=17
[17:17:12]app_bt_key 2048,5
[17:17:12]first blood!
[17:17:12]bt_key_send code:2048 evt:5
[17:17:12]bt_key_handle code:2048 evt:5
[17:17:12]
[17:17:12]==2001==vol down====event:5=
[17:17:12]
[17:17:12]app_bt_stream_volumedown set audio volume
[17:17:12]app_bt_stream_volumeset vol=16
[17:17:12]codec_int_stream_setup: stream=0
[17:17:12][sample_rate]old = 44100, new = 44100
[17:17:12][vol]old = 17, new = 16
[17:17:12][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:12][hal_codec_setup_stream] playback sample_rate=44100
[17:17:12]app_bt_stream_volumedown a2dp: 16
[17:17:12]app_bt_stream_volumedown hfp: 12
[17:17:12]hfp get vol raw:10 loc:12
[17:17:12]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:12]
[17:17:12]channelid is 0x42, mux's channelid is 0x42
[17:17:12]bt_xtal_sync: ms=62691 bit_offset=19
[17:17:12] [RX:]
[17:17:12]13 05 01 80 00 01 00
[17:17:12]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:12]
[17:17:12]bt_xtal_sync: ms=62743 bit_offset=20
[17:17:12]bt_xtal_sync: ms=62802 bit_offset=21
[17:17:12]bt_xtal_sync: ms=62830 bit_offset=22
[17:17:12]bt_xtal_sync: ms=62888 bit_offset=23
[17:17:12]bt_xtal_sync: ms=62920 bit_offset=24
[17:17:12]
[17:17:12]
[17:17:12]Update 26M: diff=11 ms=458 ppb=24011
[17:17:12]
[17:17:12]
[17:17:12]bt_xtal_sync: Bad ppb=24011 last_valid_ppb=21007 last_tune_ppb=0 (3)
[17:17:12]bt_xtal_sync: ms=62978 bit_offset=25
[17:17:12]bt_xtal_sync: ms=63005 bit_offset=26
[17:17:12]bt_xtal_sync: ms=63060 bit_offset=27
[17:17:12]bt_xtal_sync: ms=63098 bit_offset=28
[17:17:12]app_bt_accessmode_timehandler accMode=2
[17:17:12]app_bt_mail_process src_thread:0x20003768 request_id:5 enter
[17:17:12]!!!ME_SetAccessibleMode MEC(accOp).mode = 2, mode = 2
[17:17:12]
[17:17:12]app_bt_mail_process request_id:5 :status:2 exit
[17:17:12] [RX:]
[17:17:12]0e 04 05 1a 0c 00
[17:17:12]app_bt_golbal_handle evt = 10
[17:17:12]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x2
[17:17:12]bt_xtal_sync: ms=63184 bit_offset=30
[17:17:12]app_bt_key 2048,5
[17:17:12]first blood!
[17:17:12]bt_key_send code:2048 evt:5
[17:17:12]bt_key_handle code:2048 evt:5
[17:17:12]
[17:17:12]==2001==vol down====event:5=
[17:17:12]
[17:17:12]app_bt_stream_volumedown set audio volume
[17:17:12]app_bt_stream_volumeset vol=15
[17:17:12]codec_int_stream_setup: stream=0
[17:17:12][sample_rate]old = 44100, new = 44100
[17:17:12][vol]old = 16, new = 15
[17:17:12][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:12][hal_codec_setup_stream] playback sample_rate=44100
[17:17:12]app_bt_stream_volumedown a2dp: 15
[17:17:12]app_bt_stream_volumedown hfp: 12
[17:17:12]hfp get vol raw:10 loc:12
[17:17:12]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:12]
[17:17:12]channelid is 0x42, mux's channelid is 0x42
[17:17:12]bt_xtal_sync: ms=63270 bit_offset=31
[17:17:12] [RX:]
[17:17:12]13 05 01 80 00 01 00
[17:17:12]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:12]
[17:17:12]bt_xtal_sync: ms=63301 bit_offset=32
[17:17:12]bt_xtal_sync: ms=63356 bit_offset=33
[17:17:12]bt_xtal_sync: ms=63399 bit_offset=34
[17:17:12]bt_xtal_sync: ms=63454 bit_offset=35
[17:17:12]bt_xtal_sync: ms=63485 bit_offset=36
[17:17:12]bt_xtal_sync: ms=63547 bit_offset=37
[17:17:12]
[17:17:12]
[17:17:12]Update 26M: diff=10 ms=487 ppb=20513
[17:17:12]
[17:17:12]
[17:17:12]bt_xtal_sync: Bad ppb=20513 last_valid_ppb=24011 last_tune_ppb=0 (4)
[17:17:13]bt_xtal_sync: ms=63578 bit_offset=38
[17:17:13]bt_xtal_sync: ms=63641 bit_offset=39
[17:17:13]bt_xtal_sync: ms=63676 bit_offset=40
[17:17:13]bt_xtal_sync: ms=63743 bit_offset=42
[17:17:13]bt_xtal_sync: ms=63808 bit_offset=43
[17:17:13]bt_xtal_sync: ms=63840 bit_offset=44
[17:17:13]app_bt_key 2048,5
[17:17:13]first blood!
[17:17:13]bt_key_send code:2048 evt:5
[17:17:13]bt_key_handle code:2048 evt:5
[17:17:13]
[17:17:13]==2001==vol down====event:5=
[17:17:13]
[17:17:13]app_bt_stream_volumedown set audio volume
[17:17:13]app_bt_stream_volumeset vol=14
[17:17:13]codec_int_stream_setup: stream=0
[17:17:13][sample_rate]old = 44100, new = 44100
[17:17:13][vol]old = 15, new = 14
[17:17:13][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:13][hal_codec_setup_stream] playback sample_rate=44100
[17:17:13]app_bt_stream_volumedown a2dp: 14
[17:17:13]app_bt_stream_volumedown hfp: 12
[17:17:13]hfp get vol raw:10 loc:12
[17:17:13]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:13]
[17:17:13]channelid is 0x42, mux's channelid is 0x42
[17:17:13] [RX:]
[17:17:13]13 05 01 80 00 01 00
[17:17:13]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:13]
[17:17:13]bt_xtal_sync: ms=63907 bit_offset=45
[17:17:13]bt_xtal_sync: ms=63946 bit_offset=46
[17:17:13]bt_xtal_sync: ms=63978 bit_offset=47
[17:17:13]bt_xtal_sync: ms=64033 bit_offset=48
[17:17:13]bt_xtal_sync: ms=64064 bit_offset=49
[17:17:13]bt_xtal_sync: ms=64130 bit_offset=50
[17:17:13]
[17:17:13]
[17:17:13]Update 26M: diff=10 ms=453 ppb=22039
[17:17:13]
[17:17:13]
[17:17:13]bt_xtal_sync: Bad ppb=22039 last_valid_ppb=20513 last_tune_ppb=0 (5)
[17:17:13]bt_xtal_sync: ms=64184 bit_offset=51
[17:17:13]bt_xtal_sync: ms=64215 bit_offset=52
[17:17:13]bt_xtal_sync: ms=64281 bit_offset=53
[17:17:13]bt_xtal_sync: ms=64308 bit_offset=54
[17:17:13]bt_xtal_sync: ms=64379 bit_offset=56
[17:17:13],rssi_monitor[0]:914 ,rssi_monitor[1]: 209 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:13]
[17:17:13]bt_xtal_sync: ms=64463 bit_offset=58
[17:17:13]bt_xtal_sync: ms=64541 bit_offset=59
[17:17:13]app_bt_key 2048,5
[17:17:13]first blood!
[17:17:13]bt_key_send code:2048 evt:5
[17:17:13]bt_key_handle code:2048 evt:5
[17:17:13]
[17:17:13]==2001==vol down====event:5=
[17:17:13]
[17:17:13]app_bt_stream_volumedown set audio volume
[17:17:13]app_bt_stream_volumeset vol=13
[17:17:13]codec_int_stream_setup: stream=0
[17:17:13][sample_rate]old = 44100, new = 44100
[17:17:13][vol]old = 14, new = 13
[17:17:13][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:13][hal_codec_setup_stream] playback sample_rate=44100
[17:17:13]app_bt_stream_volumedown a2dp: 13
[17:17:13]app_bt_stream_volumedown hfp: 12
[17:17:13]hfp get vol raw:10 loc:12
[17:17:13]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:13]
[17:17:13]channelid is 0x42, mux's channelid is 0x42
[17:17:14]bt_xtal_sync: ms=64569 bit_offset=60
[17:17:14] [RX:]
[17:17:14]13 05 01 80 00 01 00
[17:17:14]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:14]
[17:17:14]bt_xtal_sync: ms=64632 bit_offset=61
[17:17:14]bt_xtal_sync: ms=64660 bit_offset=62
[17:17:14]bt_xtal_sync: ms=64723 bit_offset=63
[17:17:14]bt_xtal_sync: ms=64750 bit_offset=64
[17:17:14]
[17:17:14]
[17:17:14]Update 26M: diff=11 ms=468 ppb=23467
[17:17:14]
[17:17:14]
[17:17:14]bt_xtal_sync: Bad ppb=23467 last_valid_ppb=22039 last_tune_ppb=0 (6)
[17:17:14]bt_reset_xtal_to_default: Reset xtal to default 45
[17:17:14]bt_xtal_sync: ms=64813 bit_offset=65
[17:17:14]bt_xtal_sync: ms=64840 bit_offset=66
[17:17:14]bt_xtal_sync: ms=64906 bit_offset=67
[17:17:14]bt_xtal_sync: ms=64943 bit_offset=68
[17:17:14]bt_xtal_sync: ms=64978 bit_offset=69
[17:17:14]bt_xtal_sync: ms=65039 bit_offset=70
[17:17:14]bt_xtal_sync: ms=65066 bit_offset=71
[17:17:14]bt_xtal_sync: ms=65122 bit_offset=72
[17:17:14]bt_xtal_sync: ms=65186 bit_offset=73
[17:17:14]bt_xtal_sync: ms=65255 bit_offset=75
[17:17:14]bt_xtal_sync: ms=65290 bit_offset=76
[17:17:14]bt_xtal_sync: ms=65402 bit_offset=78
[17:17:14]
[17:17:14]
[17:17:14]Update 26M: diff=10 ms=496 ppb=20151
[17:17:14]
[17:17:14]
[17:17:14]bt_xtal_sync: Bad ppb=20151 last_valid_ppb=23467 last_tune_ppb=0 (1)
[17:17:14]bt_xtal_sync: ms=65430 bit_offset=79
[17:17:14]bt_xtal_sync: ms=65488 bit_offset=80
[17:17:14]bt_xtal_sync: ms=65555 bit_offset=81
[17:17:15]bt_xtal_sync: ms=65582 bit_offset=82
[17:17:15]CPU USAGE: busy=53 light_sleep=47 deep_sleep=0
[17:17:15]bt_xtal_sync: ms=65621 bit_offset=83
[17:17:15]bt_xtal_sync: ms=65683 bit_offset=84
[17:17:15]bt_xtal_sync: ms=65741 bit_offset=86
[17:17:15]bt_xtal_sync: ms=65831 bit_offset=87
[17:17:15]bt_xtal_sync: ms=65886 bit_offset=89
[17:17:15]bt_xtal_sync: ms=65932 bit_offset=90
[17:17:15]bt_xtal_sync: ms=65987 bit_offset=91
[17:17:15]app_bt_key 1024,5
[17:17:15]first blood!
[17:17:15]bt_key_send code:1024 evt:5
[17:17:15]bt_key_handle code:1024 evt:5
[17:17:15]
[17:17:15]==2001=vol up ===event:5==
[17:17:15]
[17:17:15]app_bt_stream_volumeup set audio volume
[17:17:15]app_bt_stream_volumeset vol=14
[17:17:15]codec_int_stream_setup: stream=0
[17:17:15][sample_rate]old = 44100, new = 44100
[17:17:15][vol]old = 13, new = 14
[17:17:15][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:15][hal_codec_setup_stream] playback sample_rate=44100
[17:17:15]app_bt_stream_volumeup a2dp: 14
[17:17:15]app_bt_stream_volumeup hfp: 12
[17:17:15]hfp get vol raw:10 loc:12
[17:17:15]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:15]
[17:17:15]channelid is 0x42, mux's channelid is 0x42
[17:17:15] [RX:]
[17:17:15]13 05 01 80 00 01 00
[17:17:15]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:15]
[17:17:15]bt_xtal_sync: ms=66018 bit_offset=92
[17:17:15]
[17:17:15]
[17:17:15]Update 26M: diff=11 ms=463 ppb=23720
[17:17:15]
[17:17:15]
[17:17:15]bt_xtal_sync: Bad ppb=23720 last_valid_ppb=20151 last_tune_ppb=0 (2)
[17:17:15]bt_xtal_sync: ms=66077 bit_offset=93
[17:17:15]bt_xtal_sync: ms=66181 bit_offset=95
[17:17:15]bt_xtal_sync: ms=66210 bit_offset=96
[17:17:15]bt_xtal_sync: ms=66311 bit_offset=98
[17:17:15]bt_xtal_sync: ms=66346 bit_offset=99
[17:17:15]bt_xtal_sync: ms=66440 bit_offset=101
[17:17:15]bt_xtal_sync: ms=66521 bit_offset=102
[17:17:15]bt_xtal_sync: ms=66552 bit_offset=103
[17:17:16]bt_xtal_sync: ms=66608 bit_offset=104
[17:17:16]bt_xtal_sync: ms=66665 bit_offset=106
[17:17:16],rssi_monitor[0]:355 ,rssi_monitor[1]: 186 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:16]
[17:17:16]app_bt_key 1024,5
[17:17:16]first blood!
[17:17:16]bt_key_send code:1024 evt:5
[17:17:16]bt_key_handle code:1024 evt:5
[17:17:16]
[17:17:16]==2001=vol up ===event:5==
[17:17:16]
[17:17:16]app_bt_stream_volumeup set audio volume
[17:17:16]app_bt_stream_volumeset vol=15
[17:17:16]codec_int_stream_setup: stream=0
[17:17:16][sample_rate]old = 44100, new = 44100
[17:17:16][vol]old = 14, new = 15
[17:17:16][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:16][hal_codec_setup_stream] playback sample_rate=44100
[17:17:16]app_bt_stream_volumeup a2dp: 15
[17:17:16]app_bt_stream_volumeup hfp: 12
[17:17:16]hfp get vol raw:10 loc:12
[17:17:16]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:16]
[17:17:16]channelid is 0x42, mux's channelid is 0x42
[17:17:16]bt_xtal_sync: ms=66745 bit_offset=107
[17:17:16]bt_xtal_sync: ms=66776 bit_offset=108
[17:17:16]
[17:17:16]
[17:17:16]Update 26M: diff=13 ms=566 ppb=22958
[17:17:16]
[17:17:16]
[17:17:16]bt_xtal_sync: Bad ppb=22958 last_valid_ppb=23720 last_tune_ppb=0 (3)
[17:17:16] [RX:]
[17:17:16]13 05 01 80 00 01 00
[17:17:16]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:16]
[17:17:16]bt_xtal_sync: ms=66813 bit_offset=109
[17:17:16]bt_xtal_sync: ms=66866 bit_offset=110
[17:17:16]bt_xtal_sync: ms=66892 bit_offset=111
[17:17:16]bt_xtal_sync: ms=66950 bit_offset=112
[17:17:16]bt_xtal_sync: ms=67022 bit_offset=113
[17:17:16]bt_xtal_sync: ms=67082 bit_offset=115
[17:17:16]bt_xtal_sync: ms=67132 bit_offset=116
[17:17:16]bt_xtal_sync: ms=67213 bit_offset=118
[17:17:16]bt_xtal_sync: ms=67330 bit_offset=120
[17:17:16]app_bt_key 1024,5
[17:17:16]first blood!
[17:17:16]bt_key_send code:1024 evt:5
[17:17:16]bt_key_handle code:1024 evt:5
[17:17:16]
[17:17:16]==2001=vol up ===event:5==
[17:17:16]
[17:17:16]app_bt_stream_volumeup set audio volume
[17:17:16]app_bt_stream_volumeset vol=16
[17:17:16]codec_int_stream_setup: stream=0
[17:17:16][sample_rate]old = 44100, new = 44100
[17:17:16][vol]old = 15, new = 16
[17:17:16][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:16][hal_codec_setup_stream] playback sample_rate=44100
[17:17:16]app_bt_stream_volumeup a2dp: 16
[17:17:16]app_bt_stream_volumeup hfp: 12
[17:17:16]hfp get vol raw:10 loc:12
[17:17:16]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:16]
[17:17:16]channelid is 0x42, mux's channelid is 0x42
[17:17:16]bt_xtal_sync: ms=67360 bit_offset=121
[17:17:16] [RX:]
[17:17:16]13 05 01 80 00 01 00
[17:17:16]bt_xtal_sync: ms=67388 bit_offset=122
[17:17:16]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:16]
[17:17:16]bt_xtal_sync: ms=67448 bit_offset=123
[17:17:16]
[17:17:16]
[17:17:16]Update 26M: diff=12 ms=556 ppb=21573
[17:17:16]
[17:17:16]
[17:17:16]bt_xtal_sync: Bad ppb=21573 last_valid_ppb=22958 last_tune_ppb=0 (4)
[17:17:16]bt_xtal_sync: ms=67485 bit_offset=124
[17:17:16]bt_xtal_sync: ms=67537 bit_offset=125
[17:17:17]bt_xtal_sync: ms=67626 bit_offset=127
[17:17:17]bt_xtal_sync: ms=67723 bit_offset=129
[17:17:17]bt_xtal_sync: ms=67788 bit_offset=130
[17:17:17]bt_xtal_sync: ms=67858 bit_offset=132
[17:17:17]bt_xtal_sync: ms=67948 bit_offset=134
[17:17:17]app_bt_key 1024,5
[17:17:17]first blood!
[17:17:17]bt_key_send code:1024 evt:5
[17:17:17]bt_key_handle code:1024 evt:5
[17:17:17]
[17:17:17]==2001=vol up ===event:5==
[17:17:17]
[17:17:17]app_bt_stream_volumeup set audio volume
[17:17:17]app_bt_stream_volumeset vol=17
[17:17:17]codec_int_stream_setup: stream=0
[17:17:17][sample_rate]old = 44100, new = 44100
[17:17:17][vol]old = 16, new = 17
[17:17:17][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:17][hal_codec_setup_stream] playback sample_rate=44100
[17:17:17]app_bt_stream_volumeup a2dp: 17
[17:17:17]app_bt_stream_volumeup hfp: 12
[17:17:17]hfp get vol raw:10 loc:12
[17:17:17]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:17]
[17:17:17]channelid is 0x42, mux's channelid is 0x42
[17:17:17] [RX:]
[17:17:17]13 05 01 80 00 01 00
[17:17:17]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:17]
[17:17:17]bt_xtal_sync: ms=68037 bit_offset=136
[17:17:17]bt_xtal_sync: ms=68122 bit_offset=137
[17:17:17]
[17:17:17]
[17:17:17]Update 26M: diff=10 ms=495 ppb=20177
[17:17:17]
[17:17:17]
[17:17:17]bt_xtal_sync: Bad ppb=20177 last_valid_ppb=21573 last_tune_ppb=0 (5)
[17:17:17]bt_xtal_sync: ms=68162 bit_offset=138
[17:17:17]bt_xtal_sync: ms=68222 bit_offset=140
[17:17:17]bt_xtal_sync: ms=68303 bit_offset=141
[17:17:17]bt_xtal_sync: ms=68338 bit_offset=142
[17:17:17]bt_xtal_sync: ms=68365 bit_offset=143
[17:17:17]bt_xtal_sync: ms=68423 bit_offset=144
[17:17:17]app_bt_key 1024,5
[17:17:17]first blood!
[17:17:17]bt_key_send code:1024 evt:5
[17:17:17]bt_key_handle code:1024 evt:5
[17:17:17]
[17:17:17]==2001=vol up ===event:5==
[17:17:17]
[17:17:17]app_bt_stream_volumeup set audio volume
[17:17:17]app_bt_stream_volumeset vol=18
[17:17:17]codec_int_stream_setup: stream=0
[17:17:17][sample_rate]old = 44100, new = 44100
[17:17:17][codec_int_stream_setup]stream = 0, set_flag = 2
[17:17:17][hal_codec_setup_stream] playback sample_rate=44100
[17:17:17]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1d
[17:17:17]bt_media_start media_active = 3,curr_active_media = 1
[17:17:17]set current media = 2
[17:17:17]app_bt_stream_close prev:3 cur:3
[17:17:17]bt_sbc_player work:1 op:1 freq:0 :sample:44100
[17:17:17]
[17:17:17]app_bt_stream_volumeup a2dp: 17
[17:17:17]app_bt_stream_volumeup hfp: 12
[17:17:17]hfp get vol raw:10 loc:12
[17:17:17]codec_int_stream_stop: stream=0
[17:17:17]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:17]
[17:17:17]codec_hw_stop: stream=0
[17:17:17]codec_int_stream_close: stream=0
[17:17:17]codec_int_close: type=0 running=1
[17:17:17]trig codec close
[17:17:17]codec_hw_close
[17:17:17]codec_hw_close: codec_hw_state=2
[17:17:17]bt_term_xtal_sync_default
[17:17:17]app_bt_stream_open prev:9 cur:7 freq:0
[17:17:17]app_play_audio_onoff work:0 op:1 aud_id:29
[17:17:17]app_overlay_select id:1:8
[17:17:17][af_stream_open] id = 0, stream = 0
[17:17:17]codec_int_open: running=0
[17:17:17]trig codec open
[17:17:17]codec_hw_open
[17:17:17]codec_hw_open: codec_hw_state=1
[17:17:17]codec_int_stream_open: stream=0
[17:17:17]codec_int_stream_setup: stream=0
[17:17:17][sample_rate]old = 44100, new = 16000
[17:17:17][vol]old = 17, new = 0
[17:17:17][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:17][hal_codec_setup_stream] playback sample_rate=16000
[17:17:17]codec_int_stream_start: stream=0
[17:17:17]codec_hw_start: stream=0
[17:17:17]channelid is 0x42, mux's channelid is 0x42
[17:17:17] [RX:]
[17:17:17]13 05 01 80 00 01 00
[17:17:17]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:17]
[17:17:18]app_bt_accessmode_timehandler accMode=3
[17:17:18]app_bt_mail_process src_thread:0x20003768 request_id:5 enter
[17:17:18]!!!ME_SetAccessibleMode MEC(accOp).mode = 3, mode = 3
[17:17:18]
[17:17:18]app_bt_mail_process request_id:5 :status:2 exit
[17:17:18] [RX:]
[17:17:18]0e 04 05 1a 0c 00
[17:17:18]app_bt_golbal_handle evt = 10
[17:17:18]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x3
[17:17:18]cache underflow
[17:17:18]media_sbc_decoder underflow len:256
[17:17:18]
[17:17:18]app_play_sbc_more_data-->need close, length:2480 len:256 l:0
[17:17:18]app_bt_stream_close prev:7 cur:7
[17:17:18]app_play_audio_onoff work:1 op:0 aud_id:0
[17:17:18]codec_int_stream_stop: stream=0
[17:17:18]codec_hw_stop: stream=0
[17:17:18]codec_int_stream_close: stream=0
[17:17:18]codec_int_close: type=0 running=1
[17:17:18]trig codec close
[17:17:18]codec_hw_close
[17:17:18]codec_hw_close: codec_hw_state=2
[17:17:18]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:17:18]bt_media_stop media_active = 3,curr_active_media = 2
[17:17:18]app_audio_manager_switch_a2dp switch_a2dp to id:0
[17:17:18]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:17:18]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:17:18]set current media = 1
[17:17:18]bt_media_stop end media_active = 1,curr_active_media = 1
[17:17:18]app_bt_key 1024,5
[17:17:18]first blood!
[17:17:18]bt_key_send code:1024 evt:5
[17:17:18]app_bt_stream_setup prev:9 cur:3 sample:32
[17:17:18]app_bt_stream_open prev:9 cur:3 freq:0
[17:17:18]bt_sbc_player work:0 op:0 freq:0 :sample:44100
[17:17:18]
[17:17:18]bt_media_volume_ptr_update_by_mediatype enter
[17:17:18]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:17:18]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:17:18]bt_media_volume_ptr_update_by_mediatype exit
[17:17:18]bt_sbc_player: app_sysfreq_req 2
[17:17:18]app_overlay_select id:4:8
[17:17:18]bt_init_xtal_sync
[17:17:18][af_stream_open] id = 0, stream = 0
[17:17:18]codec_int_open: running=0
[17:17:18]trig codec open
[17:17:18]codec_hw_open
[17:17:18]codec_hw_open: codec_hw_state=1
[17:17:18]codec_int_stream_open: stream=0
[17:17:18]codec_int_stream_setup: stream=0
[17:17:18][sample_rate]old = 16000, new = 44100
[17:17:18][vol]old = 0, new = 17
[17:17:18][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:18][hal_codec_setup_stream] playback sample_rate=44100
[17:17:18]a2dp_audio_init f=42768 20720
[17:17:18]
[17:17:18]codec_int_stream_start: stream=0
[17:17:18]codec_hw_start: stream=0
[17:17:18]store_sbc_buffer not ready:385 cache_status:0
[17:17:18]bt_key_handle code:1024 evt:5
[17:17:18]
[17:17:18]==2001=vol up ===event:5==
[17:17:18]
[17:17:18]app_bt_stream_volumeup set audio volume
[17:17:18]app_bt_stream_volumeset vol=18
[17:17:18]codec_int_stream_setup: stream=0
[17:17:18][sample_rate]old = 44100, new = 44100
[17:17:18][codec_int_stream_setup]stream = 0, set_flag = 2
[17:17:18][hal_codec_setup_stream] playback sample_rate=44100
[17:17:18]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1d
[17:17:18]bt_media_start media_active = 3,curr_active_media = 1
[17:17:18]set current media = 2
[17:17:18]app_bt_stream_close prev:3 cur:3
[17:17:18]bt_sbc_player work:1 op:1 freq:0 :sample:44100
[17:17:18]
[17:17:18]app_bt_stream_volumeup a2dp: 17
[17:17:18]app_bt_stream_volumeup hfp: 12
[17:17:18]codec_int_stream_stop: stream=0
[17:17:18]hfp get vol raw:10 loc:12
[17:17:18]btapp_a2dp_report_speak_gain transId:193 a2dp_state:1 streamming:1 report:00
[17:17:18]
[17:17:18]codec_hw_stop: stream=0
[17:17:18]codec_int_stream_close: stream=0
[17:17:18]codec_int_close: type=0 running=1
[17:17:18]trig codec close
[17:17:18]codec_hw_close
[17:17:18]codec_hw_close: codec_hw_state=2
[17:17:18]bt_term_xtal_sync_default
[17:17:18]app_bt_stream_open prev:9 cur:7 freq:0
[17:17:18]app_play_audio_onoff work:0 op:1 aud_id:29
[17:17:18]app_overlay_select id:1:8
[17:17:18][af_stream_open] id = 0, stream = 0
[17:17:18]codec_int_open: running=0
[17:17:18]trig codec open
[17:17:18]codec_hw_open
[17:17:18]codec_hw_open: codec_hw_state=1
[17:17:18]codec_int_stream_open: stream=0
[17:17:18]codec_int_stream_setup: stream=0
[17:17:18][sample_rate]old = 44100, new = 16000
[17:17:18][vol]old = 17, new = 0
[17:17:18][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:18][hal_codec_setup_stream] playback sample_rate=16000
[17:17:18]codec_int_stream_start: stream=0
[17:17:18]codec_hw_start: stream=0
[17:17:18]channelid is 0x42, mux's channelid is 0x42
[17:17:18] [RX:]
[17:17:18]13 05 01 80 00 01 00
[17:17:18]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c330
[17:17:18]
[17:17:19]cache underflow
[17:17:19]media_sbc_decoder underflow len:256
[17:17:19]
[17:17:19]app_play_sbc_more_data-->need close, length:2480 len:256 l:0
[17:17:19]app_bt_stream_close prev:7 cur:7
[17:17:19]app_play_audio_onoff work:1 op:0 aud_id:0
[17:17:19]codec_int_stream_stop: stream=0
[17:17:19]codec_hw_stop: stream=0
[17:17:19]codec_int_stream_close: stream=0
[17:17:19]codec_int_close: type=0 running=1
[17:17:19]trig codec close
[17:17:19]codec_hw_close
[17:17:19]codec_hw_close: codec_hw_state=2
[17:17:19]STREAM MANAGE bt_media_stop type= 2,device id = 0
[17:17:19]bt_media_stop media_active = 3,curr_active_media = 2
[17:17:19]app_audio_manager_switch_a2dp switch_a2dp to id:0
[17:17:19]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:17:19]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:17:19]set current media = 1
[17:17:19]bt_media_stop end media_active = 1,curr_active_media = 1
[17:17:19]app_bt_stream_setup prev:9 cur:3 sample:32
[17:17:19]app_bt_stream_open prev:9 cur:3 freq:0
[17:17:19]bt_sbc_player work:0 op:0 freq:0 :sample:44100
[17:17:19]
[17:17:19]bt_media_volume_ptr_update_by_mediatype enter
[17:17:19]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:17:19]app_bt_stream_volume_ptr_update a2dp_vol:17 hfp_vol:12 ptr:0x20000378
[17:17:19]bt_media_volume_ptr_update_by_mediatype exit
[17:17:19]bt_sbc_player: app_sysfreq_req 2
[17:17:19]app_overlay_select id:4:8
[17:17:19]bt_init_xtal_sync
[17:17:19][af_stream_open] id = 0, stream = 0
[17:17:19]codec_int_open: running=0
[17:17:19]trig codec open
[17:17:19]codec_hw_open
[17:17:19]codec_hw_open: codec_hw_state=1
[17:17:19]codec_int_stream_open: stream=0
[17:17:19]codec_int_stream_setup: stream=0
[17:17:19][sample_rate]old = 16000, new = 44100
[17:17:19][vol]old = 0, new = 17
[17:17:19][codec_int_stream_setup]stream = 0, set_flag = 12
[17:17:19][hal_codec_setup_stream] playback sample_rate=44100
[17:17:19]a2dp_audio_init f=42768 20720
[17:17:19]
[17:17:19]codec_int_stream_start: stream=0
[17:17:19]codec_hw_start: stream=0
[17:17:19]store_sbc_buffer not ready:330 cache_status:0
[17:17:19]store_sbc_buffer not ready:334 cache_status:0
[17:17:19]bt_xtal_sync: ms=69590 bit_offset=169
[17:17:19]a2dp_audio_more_data cache not ready skip frame 2
[17:17:19]
[17:17:19]bt_xtal_sync: ms=69621 bit_offset=170
[17:17:19]a2dp_audio_more_data cache not ready skip frame 2
[17:17:19]
[17:17:19]bt_xtal_sync: ms=69648 bit_offset=171
[17:17:19]a2dp_audio_more_data cache not ready skip frame 2
[17:17:19]
[17:17:19]a2dp_audio_more_data cache not ready skip frame 2
[17:17:19]
[17:17:19]aac cache ok:2556
[17:17:19]
[17:17:19]bt_xtal_sync: ms=69722 bit_offset=172
[17:17:19]bt_xtal_sync: ms=69748 bit_offset=173
[17:17:19]bt_xtal_sync: ms=69818 bit_offset=175
[17:17:19]bt_xtal_sync: ms=69885 bit_offset=176
[17:17:19]bt_xtal_sync: ms=69955 bit_offset=177
[17:17:19]bt_xtal_sync: ms=69989 bit_offset=178
[17:17:19]bt_xtal_sync: ms=70043 bit_offset=179
[17:17:19]bt_xtal_sync: ms=70071 bit_offset=180
[17:17:19]bt_xtal_sync: ms=70130 bit_offset=181
[17:17:19]
[17:17:19]
[17:17:19]Update 26M: diff=10 ms=481 ppb=20779
[17:17:19]
[17:17:19]
[17:17:19]bt_xtal_sync: Bad ppb=20779 last_valid_ppb=-2147483648 last_tune_ppb=0 (1)
[17:17:19]*** SYSFREQ user=3 freq=2
[17:17:19]*** SYSFREQ user=4 freq=2
[17:17:19]*** SYSFREQ top_user=4
[17:17:19]app_battery_handle_process 0,3680
[17:17:19]checker: id:0 state:3 mode:0 role:1 cmghdl:2000d700 sniffInterva:0/800 IsAudioUp:0
[17:17:19]0x65 0x54 0x7a 0x34 0xa1 0x84
[17:17:19]remDev:2000e3e8 a2dp State:3 remDev:2000e3e8 hf_channel Connected:1 remDev:2000e3e8
[17:17:19]bt_xtal_sync: ms=70157 bit_offset=182
[17:17:19],rssi_monitor[0]:930 ,rssi_monitor[1]: 210 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:19]
[17:17:19]bt_xtal_sync: ms=70267 bit_offset=184
[17:17:19]bt_xtal_sync: ms=70305 bit_offset=185
[17:17:19]bt_xtal_sync: ms=70336 bit_offset=186
[17:17:19]bt_xtal_sync: ms=70362 bit_offset=187
[17:17:19]bt_xtal_sync: ms=70420 bit_offset=188
[17:17:19]bt_xtal_sync: ms=70482 bit_offset=189
[17:17:19]bt_xtal_sync: ms=70516 bit_offset=190
[17:17:20]bt_xtal_sync: ms=70625 bit_offset=192
[17:17:20]bt_xtal_sync: ms=70685 bit_offset=193
[17:17:20]bt_xtal_sync: ms=70715 bit_offset=194
[17:17:20]bt_xtal_sync: ms=70750 bit_offset=195
[17:17:20]
[17:17:20]
[17:17:20]Update 26M: diff=11 ms=445 ppb=24719
[17:17:20]
[17:17:20]
[17:17:20]bt_xtal_sync: Bad ppb=24719 last_valid_ppb=20779 last_tune_ppb=0 (2)
[17:17:20]bt_xtal_sync: ms=70816 bit_offset=196
[17:17:20]bt_xtal_sync: ms=70848 bit_offset=197
[17:17:20]bt_xtal_sync: ms=70906 bit_offset=198
[17:17:20]bt_xtal_sync: ms=70938 bit_offset=199
[17:17:20]bt_xtal_sync: ms=71000 bit_offset=200
[17:17:20]bt_xtal_sync: ms=71031 bit_offset=201
[17:17:20]bt_xtal_sync: ms=71084 bit_offset=202
[17:17:20]bt_xtal_sync: ms=71141 bit_offset=203
[17:17:20]bt_xtal_sync: ms=71176 bit_offset=204
[17:17:20]bt_xtal_sync: ms=71232 bit_offset=205
[17:17:20]bt_xtal_sync: ms=71266 bit_offset=206
[17:17:20]bt_xtal_sync: ms=71320 bit_offset=207
[17:17:20]bt_xtal_sync: ms=71355 bit_offset=208
[17:17:20]bt_xtal_sync: ms=71415 bit_offset=209
[17:17:20]bt_xtal_sync: ms=71441 bit_offset=210
[17:17:20]
[17:17:20]
[17:17:20]Update 26M: diff=12 ms=535 ppb=22430
[17:17:20]
[17:17:20]
[17:17:20]bt_xtal_sync: Bad ppb=22430 last_valid_ppb=24719 last_tune_ppb=0 (3)
[17:17:20]bt_xtal_sync: ms=71547 bit_offset=212
[17:17:21]bt_xtal_sync: ms=71610 bit_offset=214
[17:17:21]bt_xtal_sync: ms=71714 bit_offset=216
[17:17:21]bt_xtal_sync: ms=71741 bit_offset=217
[17:17:21]bt_xtal_sync: ms=71804 bit_offset=218
[17:17:21]bt_xtal_sync: ms=71831 bit_offset=219
[17:17:21]bt_xtal_sync: ms=71898 bit_offset=220
[17:17:21]bt_xtal_sync: ms=71929 bit_offset=221
[17:17:21]bt_xtal_sync: ms=71991 bit_offset=222
[17:17:21]bt_xtal_sync: ms=72019 bit_offset=223
[17:17:21]bt_xtal_sync: ms=72086 bit_offset=224
[17:17:21]bt_xtal_sync: ms=72125 bit_offset=225
[17:17:21]
[17:17:21]
[17:17:21]Update 26M: diff=10 ms=411 ppb=24316
[17:17:21]
[17:17:21]
[17:17:21]bt_xtal_sync: Bad ppb=24316 last_valid_ppb=22430 last_tune_ppb=0 (4)
[17:17:21]bt_xtal_sync: ms=72184 bit_offset=226
[17:17:21]bt_xtal_sync: ms=72214 bit_offset=227
[17:17:21]bt_xtal_sync: ms=72273 bit_offset=228
[17:17:21]bt_xtal_sync: ms=72333 bit_offset=230
[17:17:21]bt_xtal_sync: ms=72395 bit_offset=231
[17:17:21]bt_xtal_sync: ms=72450 bit_offset=232
[17:17:21]bt_xtal_sync: ms=72476 bit_offset=233
[17:17:21],rssi_monitor[0]:179 ,rssi_monitor[1]: 175 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:21]
[17:17:21]bt_xtal_sync: ms=72528 bit_offset=234
[17:17:22]bt_xtal_sync: ms=72624 bit_offset=236
[17:17:22]bt_xtal_sync: ms=72710 bit_offset=238
[17:17:22]bt_xtal_sync: ms=72745 bit_offset=239
[17:17:22]bt_xtal_sync: ms=72829 bit_offset=240
[17:17:22]
[17:17:22]
[17:17:22]Update 26M: diff=12 ms=556 ppb=21573
[17:17:22]
[17:17:22]
[17:17:22]bt_xtal_sync: Bad ppb=21573 last_valid_ppb=24316 last_tune_ppb=0 (5)
[17:17:22]bt_xtal_sync: ms=72896 bit_offset=242
[17:17:22]bt_xtal_sync: ms=72981 bit_offset=244
[17:17:22]bt_xtal_sync: ms=73063 bit_offset=245
[17:17:22]bt_xtal_sync: ms=73090 bit_offset=246
[17:17:22]bt_xtal_sync: ms=73149 bit_offset=247
[17:17:22]bt_xtal_sync: ms=73176 bit_offset=248
[17:17:22]bt_xtal_sync: ms=73236 bit_offset=249
[17:17:22]bt_xtal_sync: ms=73262 bit_offset=250
[17:17:22]bt_xtal_sync: ms=73315 bit_offset=251
[17:17:22]bt_xtal_sync: ms=73369 bit_offset=252
[17:17:22]bt_xtal_sync: ms=73400 bit_offset=253
[17:17:22]bt_xtal_sync: ms=73455 bit_offset=254
[17:17:22]
[17:17:22]
[17:17:22]Update 26M: diff=10 ms=392 ppb=25478
[17:17:22]
[17:17:22]
[17:17:22]bt_xtal_sync: Bad ppb=25478 last_valid_ppb=21573 last_tune_ppb=0 (6)
[17:17:22]bt_reset_xtal_to_default: Reset xtal to default 45
[17:17:22]bt_xtal_sync: ms=73483 bit_offset=255
[17:17:22]bt_xtal_sync: ms=73541 bit_offset=256
[17:17:23]bt_xtal_sync: ms=73569 bit_offset=257
[17:17:23]bt_xtal_sync: ms=73623 bit_offset=258
[17:17:23]bt_xtal_sync: ms=73655 bit_offset=259
[17:17:23]bt_xtal_sync: ms=73718 bit_offset=260
[17:17:23]bt_xtal_sync: ms=73748 bit_offset=261
[17:17:23]bt_xtal_sync: ms=73805 bit_offset=262
[17:17:23]bt_xtal_sync: ms=73832 bit_offset=263
[17:17:23]bt_xtal_sync: ms=73889 bit_offset=264
[17:17:23]bt_xtal_sync: ms=73943 bit_offset=265
[17:17:23]bt_xtal_sync: ms=73970 bit_offset=266
[17:17:23]bt_xtal_sync: ms=74025 bit_offset=267
[17:17:23]bt_xtal_sync: ms=74051 bit_offset=268
[17:17:23]bt_xtal_sync: ms=74106 bit_offset=269
[17:17:23]
[17:17:23]
[17:17:23]Update 26M: diff=12 ms=536 ppb=22352
[17:17:23]
[17:17:23]
[17:17:23]bt_xtal_sync: Bad ppb=22352 last_valid_ppb=25478 last_tune_ppb=0 (1)
[17:17:23]bt_xtal_sync: ms=74173 bit_offset=270
[17:17:23]bt_xtal_sync: ms=74198 bit_offset=271
[17:17:23]bt_xtal_sync: ms=74284 bit_offset=273
[17:17:23]bt_xtal_sync: ms=74339 bit_offset=274
[17:17:23]bt_xtal_sync: ms=74429 bit_offset=276
[17:17:23]bt_xtal_sync: ms=74484 bit_offset=277
[17:17:23]bt_xtal_sync: ms=74548 bit_offset=278
[17:17:23]bt_xtal_sync: ms=74576 bit_offset=279
[17:17:24]bt_xtal_sync: ms=74605 bit_offset=280
[17:17:24]bt_xtal_sync: ms=74658 bit_offset=281
[17:17:24]bt_xtal_sync: ms=74693 bit_offset=282
[17:17:24]bt_xtal_sync: ms=74757 bit_offset=283
[17:17:24]
[17:17:24]
[17:17:24]Update 26M: diff=11 ms=473 ppb=23250
[17:17:24]
[17:17:24]
[17:17:24]bt_xtal_sync: Bad ppb=23250 last_valid_ppb=22352 last_tune_ppb=0 (2)
[17:17:24]bt_xtal_sync: ms=74784 bit_offset=284
[17:17:24],rssi_monitor[0]:183 ,rssi_monitor[1]: 206 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:24]
[17:17:24]bt_xtal_sync: ms=74866 bit_offset=285
[17:17:24]bt_xtal_sync: ms=74921 bit_offset=287
[17:17:24]bt_xtal_sync: ms=75005 bit_offset=288
[17:17:24]bt_xtal_sync: ms=75031 bit_offset=289
[17:17:24]bt_xtal_sync: ms=75091 bit_offset=290
[17:17:24]bt_xtal_sync: ms=75123 bit_offset=291
[17:17:24]bt_xtal_sync: ms=75153 bit_offset=292
[17:17:24]bt_xtal_sync: ms=75211 bit_offset=293
[17:17:24]bt_xtal_sync: ms=75294 bit_offset=295
[17:17:24]bt_xtal_sync: ms=75386 bit_offset=297
[17:17:24]bt_xtal_sync: ms=75456 bit_offset=298
[17:17:24]
[17:17:24]
[17:17:24]Update 26M: diff=11 ms=535 ppb=20537
[17:17:24]
[17:17:24]
[17:17:24]bt_xtal_sync: Bad ppb=20537 last_valid_ppb=23250 last_tune_ppb=0 (3)
[17:17:24]bt_xtal_sync: ms=75495 bit_offset=299
[17:17:24]bt_xtal_sync: ms=75530 bit_offset=300
[17:17:25]bt_xtal_sync: ms=75588 bit_offset=301
[17:17:25]CPU USAGE: busy=55 light_sleep=45 deep_sleep=0
[17:17:25]bt_xtal_sync: ms=75615 bit_offset=302
[17:17:25]bt_xtal_sync: ms=75669 bit_offset=303
[17:17:25]bt_xtal_sync: ms=75700 bit_offset=304
[17:17:25]bt_xtal_sync: ms=75763 bit_offset=305
[17:17:25]bt_xtal_sync: ms=75800 bit_offset=306
[17:17:25]bt_xtal_sync: ms=75828 bit_offset=307
[17:17:25]bt_xtal_sync: ms=75893 bit_offset=308
[17:17:25]bt_xtal_sync: ms=75920 bit_offset=309
[17:17:25]bt_xtal_sync: ms=75980 bit_offset=310
[17:17:25]bt_xtal_sync: ms=76061 bit_offset=312
[17:17:25]bt_xtal_sync: ms=76150 bit_offset=314
[17:17:25]bt_xtal_sync: ms=76231 bit_offset=315
[17:17:25]
[17:17:25]
[17:17:25]Update 26M: diff=13 ms=642 ppb=20233
[17:17:25]
[17:17:25]
[17:17:25]bt_xtal_sync: Bad ppb=20233 last_valid_ppb=20537 last_tune_ppb=0 (4)
[17:17:25]bt_xtal_sync: ms=76257 bit_offset=316
[17:17:25]bt_xtal_sync: ms=76310 bit_offset=317
[17:17:25]bt_xtal_sync: ms=76336 bit_offset=318
[17:17:25]bt_xtal_sync: ms=76389 bit_offset=319
[17:17:25]bt_xtal_sync: ms=76470 bit_offset=321
[17:17:25]bt_xtal_sync: ms=76551 bit_offset=322
[17:17:26]bt_xtal_sync: ms=76588 bit_offset=323
[17:17:26]bt_xtal_sync: ms=76668 bit_offset=325
[17:17:26]bt_xtal_sync: ms=76704 bit_offset=326
[17:17:26]bt_xtal_sync: ms=76790 bit_offset=328
[17:17:26]bt_xtal_sync: ms=76876 bit_offset=329
[17:17:26]bt_xtal_sync: ms=76910 bit_offset=330
[17:17:26]
[17:17:26]
[17:17:26]Update 26M: diff=12 ms=574 ppb=20892
[17:17:26]
[17:17:26]
[17:17:26]bt_xtal_sync: Bad ppb=20892 last_valid_ppb=20233 last_tune_ppb=0 (5)
[17:17:26]bt_xtal_sync: ms=76966 bit_offset=331
[17:17:26]bt_xtal_sync: ms=76994 bit_offset=332
[17:17:26]bt_xtal_sync: ms=77053 bit_offset=333
[17:17:26]bt_xtal_sync: ms=77080 bit_offset=334
[17:17:26]bt_xtal_sync: ms=77143 bit_offset=335
[17:17:26],rssi_monitor[0]:229 ,rssi_monitor[1]: 198 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[17:17:26]
[17:17:26]bt_xtal_sync: ms=77169 bit_offset=336
[17:17:26]bt_xtal_sync: ms=77223 bit_offset=337