[09:51:32][TX]
[09:51:32]01 35 0c 05 01 80 00 01 00
[09:51:33]CPU USAGE: busy=1 light_sleep=98 deep_sleep=1
[09:51:36]*** SYSFREQ top_user=0
[09:51:36]app_battery_handle_process 0,4064
[09:51:36]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:51:36]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:51:36]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:51:43]CPU USAGE: busy=1 light_sleep=99 deep_sleep=0
[09:51:46]*** SYSFREQ top_user=0
[09:51:46]app_battery_handle_process 0,4064
[09:51:46]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:51:46]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:51:46]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:51:50] [RX:]
[09:51:50]14 06 00 80 00 00 00 00
[09:51:50][linkplay_spp_cmd_received]Receive data: len:5
[09:51:50]0x16 0x80 0x01 0x00 0x02
[09:51:50]currentPosition: 0, currDataLen:5
[09:51:50]Receive data:
[09:51:50]0x16 0x80 0x01 0x00 0x02
[09:51:50]App_To_Device_UpdateAlexaState:e_state 2 is error!!!
[09:51:51][TX]
[09:51:51]01 35 0c 05 01 80 00 01 00
[09:51:53]CPU USAGE: busy=1 light_sleep=98 deep_sleep=1
[09:51:56] [RX:]
[09:51:56]14 06 00 80 00 02 20 03
[09:51:56]hal_pwrkey_irqhandler: 00000001
[09:51:56]
[09:51:56]====pwr key (1,1,0)=(1344323)==
[09:51:56]
[09:51:56]*** SYSFREQ top_user=0
[09:51:56]app_battery_handle_process 0,4066
[09:51:56]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:51:56]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:51:56]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:51:56]hal_pwrkey_irqhandler: 00000001
[09:51:56]
[09:51:56]====pwr key (1,1,0)=(1348229)==
[09:51:56]
[09:51:57]app_bt_key 1,6
[09:51:57]double kill
[09:51:57]bt_key_send code:1 evt:6
[09:51:57]bt_key_handle code:1 evt:6
[09:51:57]!!!APP_KEY_EVENT_DOUBLECLICK
[09:51:57]
[09:51:59]hal_pwrkey_irqhandler: 00000001
[09:51:59]
[09:51:59]====pwr key (1,1,0)=(1386274)==
[09:51:59]
[09:51:59]hal_pwrkey_irqhandler: 00000001
[09:51:59]
[09:51:59]====pwr key (1,1,0)=(1390644)==
[09:51:59]
[09:51:59]app_bt_key 1,6
[09:51:59]double kill
[09:51:59]bt_key_send code:1 evt:6
[09:51:59]bt_key_handle code:1 evt:6
[09:51:59]!!!APP_KEY_EVENT_DOUBLECLICK
[09:51:59]
[09:52:03]CPU USAGE: busy=1 light_sleep=99 deep_sleep=0
[09:52:06]*** SYSFREQ top_user=0
[09:52:06]app_battery_handle_process 0,4068
[09:52:06]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:52:06]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:52:06]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:52:13]CPU USAGE: busy=2 light_sleep=32 deep_sleep=66
[09:52:16]*** SYSFREQ top_user=0
[09:52:16]app_battery_handle_process 0,4068
[09:52:16]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:52:16]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:52:16]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:52:23]CPU USAGE: busy=1 light_sleep=0 deep_sleep=99
[09:52:26]*** SYSFREQ top_user=0
[09:52:26]app_battery_handle_process 0,4068
[09:52:26]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:52:26]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:52:26]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:52:33]CPU USAGE: busy=1 light_sleep=0 deep_sleep=99
[09:52:36]*** SYSFREQ top_user=0
[09:52:36]app_battery_handle_process 0,4069
[09:52:36]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:52:36]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:52:36]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:52:40]hal_pwrkey_irqhandler: 00000001
[09:52:40]
[09:52:40]====pwr key (1,1,0)=(2042483)==
[09:52:40]
[09:52:40]hal_pwrkey_irqhandler: 00000001
[09:52:40]
[09:52:40]====pwr key (1,1,0)=(2046722)==
[09:52:40]
[09:52:40]app_bt_key 1,6
[09:52:40]double kill
[09:52:40]bt_key_send code:1 evt:6
[09:52:40]bt_key_handle code:1 evt:6
[09:52:40]!!!APP_KEY_EVENT_DOUBLECLICK
[09:52:40]
[09:52:42]hal_pwrkey_irqhandler: 00000001
[09:52:42]
[09:52:42]====pwr key (1,1,0)=(2074301)==
[09:52:42]
[09:52:42]hal_pwrkey_irqhandler: 00000001
[09:52:42]
[09:52:42]====pwr key (0,1,1)=(2074301)==
[09:52:42]
[09:52:42]app_bt_key 1,5
[09:52:42]first blood!
[09:52:42]bt_key_send code:1 evt:5
[09:52:42]bt_key_handle code:1 evt:5
[09:52:42]
[09:52:42]==powerkey==play pause func=====
[09:52:42]
[09:52:42]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:52:43]CPU USAGE: busy=1 light_sleep=8 deep_sleep=91
[09:52:46]*** SYSFREQ top_user=0
[09:52:46]app_battery_handle_process 0,4069
[09:52:46]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:52:46]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:52:46]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:52:54]CPU USAGE: busy=1 light_sleep=0 deep_sleep=99
[09:52:54][TX]
[09:52:54]01 35 0c 05 01 80 00 01 00
[09:52:56]*** SYSFREQ top_user=0
[09:52:56]app_battery_handle_process 0,4071
[09:52:56]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:52:56]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:52:56]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:53:04]CPU USAGE: busy=1 light_sleep=99 deep_sleep=0
[09:53:06]*** SYSFREQ top_user=0
[09:53:06]app_battery_handle_process 0,4071
[09:53:06]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:53:06]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:53:06]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:53:13]CPU USAGE: busy=1 light_sleep=99 deep_sleep=0
[09:53:16]*** SYSFREQ top_user=0
[09:53:16]app_battery_handle_process 0,4071
[09:53:16]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:53:16]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:53:16]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:53:17]hal_pwrkey_irqhandler: 00000001
[09:53:17]
[09:53:17]====pwr key (1,1,0)=(2641192)==
[09:53:17]
[09:53:17]hal_pwrkey_irqhandler: 00000001
[09:53:17]
[09:53:17]====pwr key (1,1,0)=(2641192)==
[09:53:17]
[09:53:17]app_bt_key 1,5
[09:53:17]first blood!
[09:53:17]bt_key_send code:1 evt:5
[09:53:17]bt_key_handle code:1 evt:5
[09:53:17]
[09:53:17]==powerkey==play pause func=====
[09:53:17]
[09:53:17]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:20]hal_pwrkey_irqhandler: 00000001
[09:53:20]
[09:53:20]====pwr key (1,1,0)=(2681673)==
[09:53:20]
[09:53:20]hal_pwrkey_irqhandler: 00000001
[09:53:20]
[09:53:20]====pwr key (1,1,0)=(2681673)==
[09:53:20]
[09:53:20]app_bt_key 1,5
[09:53:20]first blood!
[09:53:20]bt_key_send code:1 evt:5
[09:53:20]bt_key_handle code:1 evt:5
[09:53:20]
[09:53:20]==powerkey==play pause func=====
[09:53:20]
[09:53:20]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:21]hal_pwrkey_irqhandler: 00000001
[09:53:21]
[09:53:21]====pwr key (1,1,0)=(2707359)==
[09:53:21]
[09:53:22]app_bt_key 1,5
[09:53:22]first blood!
[09:53:22]bt_key_send code:1 evt:5
[09:53:22]bt_key_handle code:1 evt:5
[09:53:22]
[09:53:22]==powerkey==play pause func=====
[09:53:22]
[09:53:22]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:23]hal_pwrkey_irqhandler: 00000001
[09:53:23]
[09:53:23]====pwr key (1,1,0)=(2727494)==
[09:53:23]
[09:53:23]hal_pwrkey_irqhandler: 00000001
[09:53:23]
[09:53:23]====pwr key (1,1,0)=(2727494)==
[09:53:23]
[09:53:23]app_bt_key 1,5
[09:53:23]first blood!
[09:53:23]bt_key_send code:1 evt:5
[09:53:23]bt_key_handle code:1 evt:5
[09:53:23]
[09:53:23]==powerkey==play pause func=====
[09:53:23]
[09:53:23]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:23]hal_pwrkey_irqhandler: 00000001
[09:53:23]
[09:53:23]====pwr key (1,1,0)=(2738217)==
[09:53:23]
[09:53:23]CPU USAGE: busy=1 light_sleep=44 deep_sleep=55
[09:53:23]app_bt_key 1,5
[09:53:23]first blood!
[09:53:23]bt_key_send code:1 evt:5
[09:53:23]bt_key_handle code:1 evt:5
[09:53:23]
[09:53:23]==powerkey==play pause func=====
[09:53:23]
[09:53:24]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:24]hal_pwrkey_irqhandler: 00000001
[09:53:24]
[09:53:24]====pwr key (1,1,0)=(2749609)==
[09:53:24]
[09:53:24]app_bt_key 1,5
[09:53:24]first blood!
[09:53:24]bt_key_send code:1 evt:5
[09:53:24]bt_key_handle code:1 evt:5
[09:53:24]
[09:53:24]==powerkey==play pause func=====
[09:53:24]
[09:53:24]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:24]hal_pwrkey_irqhandler: 00000001
[09:53:24]
[09:53:24]====pwr key (1,1,0)=(2756775)==
[09:53:24]
[09:53:25]hal_pwrkey_irqhandler: 00000001
[09:53:25]
[09:53:25]====pwr key (1,1,0)=(2756775)==
[09:53:25]
[09:53:25]app_bt_key 1,5
[09:53:25]first blood!
[09:53:25]bt_key_send code:1 evt:5
[09:53:25]bt_key_handle code:1 evt:5
[09:53:25]
[09:53:25]==powerkey==play pause func=====
[09:53:25]
[09:53:25]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:25]hal_pwrkey_irqhandler: 00000001
[09:53:25]
[09:53:25]====pwr key (1,1,0)=(2763255)==
[09:53:25]
[09:53:25]hal_pwrkey_irqhandler: 00000001
[09:53:25]
[09:53:25]====pwr key (1,1,0)=(2763255)==
[09:53:25]
[09:53:25]app_bt_key 1,5
[09:53:25]first blood!
[09:53:25]bt_key_send code:1 evt:5
[09:53:25]bt_key_handle code:1 evt:5
[09:53:25]
[09:53:25]==powerkey==play pause func=====
[09:53:25]
[09:53:25]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:25]hal_pwrkey_irqhandler: 00000001
[09:53:25]
[09:53:25]====pwr key (1,1,0)=(2768755)==
[09:53:25]
[09:53:25]hal_pwrkey_irqhandler: 00000001
[09:53:25]
[09:53:25]====pwr key (1,1,0)=(2768755)==
[09:53:25]
[09:53:25]app_bt_key 1,5
[09:53:25]first blood!
[09:53:25]bt_key_send code:1 evt:5
[09:53:25]bt_key_handle code:1 evt:5
[09:53:25]
[09:53:25]==powerkey==play pause func=====
[09:53:25]
[09:53:25]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:25]hal_pwrkey_irqhandler: 00000001
[09:53:25]
[09:53:25]====pwr key (1,1,0)=(2774209)==
[09:53:25]
[09:53:26]hal_pwrkey_irqhandler: 00000001
[09:53:26]
[09:53:26]====pwr key (1,1,0)=(2774209)==
[09:53:26]
[09:53:26]app_bt_key 1,5
[09:53:26]first blood!
[09:53:26]bt_key_send code:1 evt:5
[09:53:26]bt_key_handle code:1 evt:5
[09:53:26]
[09:53:26]==powerkey==play pause func=====
[09:53:26]
[09:53:26]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:26]hal_pwrkey_irqhandler: 00000001
[09:53:26]
[09:53:26]====pwr key (1,1,0)=(2780171)==
[09:53:26]
[09:53:26]hal_pwrkey_irqhandler: 00000001
[09:53:26]
[09:53:26]====pwr key (0,1,1)=(2780171)==
[09:53:26]
[09:53:26]app_bt_key 1,5
[09:53:26]first blood!
[09:53:26]bt_key_send code:1 evt:5
[09:53:26]bt_key_handle code:1 evt:5
[09:53:26]
[09:53:26]==powerkey==play pause func=====
[09:53:26]
[09:53:26]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:26]*** SYSFREQ top_user=0
[09:53:26]app_battery_handle_process 0,4069
[09:53:26]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:53:26]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:53:26]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:53:26]hal_pwrkey_irqhandler: 00000001
[09:53:26]
[09:53:26]====pwr key (1,1,0)=(2789298)==
[09:53:26]
[09:53:27]app_bt_key 1,5
[09:53:27]first blood!
[09:53:27]bt_key_send code:1 evt:5
[09:53:27]bt_key_handle code:1 evt:5
[09:53:27]
[09:53:27]==powerkey==play pause func=====
[09:53:27]
[09:53:27]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:27]hal_pwrkey_irqhandler: 00000001
[09:53:27]
[09:53:27]====pwr key (1,1,0)=(2806579)==
[09:53:27]
[09:53:28]hal_pwrkey_irqhandler: 00000001
[09:53:28]
[09:53:28]====pwr key (0,1,1)=(2806579)==
[09:53:28]
[09:53:28]app_bt_key 1,5
[09:53:28]first blood!
[09:53:28]bt_key_send code:1 evt:5
[09:53:28]bt_key_handle code:1 evt:5
[09:53:28]
[09:53:28]==powerkey==play pause func=====
[09:53:28]
[09:53:28]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:28]hal_pwrkey_irqhandler: 00000001
[09:53:28]
[09:53:28]====pwr key (1,1,0)=(2813700)==
[09:53:28]
[09:53:28]hal_pwrkey_irqhandler: 00000001
[09:53:28]
[09:53:28]====pwr key (0,1,1)=(2813700)==
[09:53:28]
[09:53:28]app_bt_key 1,5
[09:53:28]first blood!
[09:53:28]hal_pwrkey_irqhandler: 00000001
[09:53:28]
[09:53:28]====pwr key (1,1,0)=(2818851)==
[09:53:28]
[09:53:28]bt_key_send code:1 evt:5
[09:53:28]bt_key_handle code:1 evt:5
[09:53:28]
[09:53:28]==powerkey==play pause func=====
[09:53:28]
[09:53:28]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:53:29]hal_pwrkey_irqhandler: 00000001
[09:53:29]
[09:53:29]====pwr key (1,1,0)=(2823246)==
[09:53:29]
[09:53:29]hal_pwrkey_irqhandler: 00000001
[09:53:29]
[09:53:29]====pwr key (0,1,1)=(2823246)==
[09:53:29]
[09:53:29]app_bt_key 1,6
[09:53:29]double kill
[09:53:29]bt_key_send code:1 evt:6
[09:53:29]bt_key_handle code:1 evt:6
[09:53:29]!!!APP_KEY_EVENT_DOUBLECLICK
[09:53:29]
[09:53:30]app_bt_key 1024,5
[09:53:30]first blood!
[09:53:30]bt_key_send code:1024 evt:5
[09:53:30]bt_key_handle code:1024 evt:5
[09:53:30]
[09:53:30]==2001=vol up ===event:5==
[09:53:30]
[09:53:30]
[09:53:30]===save_cur_bt_adr()===
[09:53:30]
[09:53:30]app_bt_key 1024,5
[09:53:30]first blood!
[09:53:30]bt_key_send code:1024 evt:5
[09:53:30]bt_key_handle code:1024 evt:5
[09:53:30]
[09:53:30]==2001=vol up ===event:5==
[09:53:30]
[09:53:30]
[09:53:30]===save_cur_bt_adr()===
[09:53:30]
[09:53:31]app_bt_key 1024,5
[09:53:31]first blood!
[09:53:31]bt_key_send code:1024 evt:5
[09:53:31]bt_key_handle code:1024 evt:5
[09:53:31]
[09:53:31]==2001=vol up ===event:5==
[09:53:31]
[09:53:31]
[09:53:31]===save_cur_bt_adr()===
[09:53:31]
[09:53:31]app_bt_key 1024,5
[09:53:31]first blood!
[09:53:31]bt_key_send code:1024 evt:5
[09:53:31]bt_key_handle code:1024 evt:5
[09:53:31]
[09:53:31]==2001=vol up ===event:5==
[09:53:31]
[09:53:31]
[09:53:31]===save_cur_bt_adr()===
[09:53:31]
[09:53:32]app_bt_key 1024,5
[09:53:32]first blood!
[09:53:32]bt_key_send code:1024 evt:5
[09:53:32]bt_key_handle code:1024 evt:5
[09:53:32]
[09:53:32]==2001=vol up ===event:5==
[09:53:32]
[09:53:32]
[09:53:32]===save_cur_bt_adr()===
[09:53:32]
[09:53:32]app_bt_key 1024,5
[09:53:32]first blood!
[09:53:32]bt_key_send code:1024 evt:5
[09:53:32]bt_key_handle code:1024 evt:5
[09:53:32]
[09:53:32]==2001=vol up ===event:5==
[09:53:32]
[09:53:32]
[09:53:32]===save_cur_bt_adr()===
[09:53:32]
[09:53:33]app_bt_key 2048,5
[09:53:33]first blood!
[09:53:33]bt_key_send code:2048 evt:5
[09:53:33]bt_key_handle code:2048 evt:5
[09:53:33]
[09:53:33]==2001==vol down====event:5=
[09:53:33]
[09:53:33]app_bt_stream_volumedown set idle volume
[09:53:33]app_bt_stream_volumedown a2dp: 11
[09:53:33]app_bt_stream_volumedown hfp: 12
[09:53:33]btapp_a2dp_report_speak_gain transId:193 a2dp_state:0 streamming:0 report:00
[09:53:33]
[09:53:33]app_bt_key 2048,5
[09:53:33]first blood!
[09:53:33]bt_key_send code:2048 evt:5
[09:53:33]bt_key_handle code:2048 evt:5
[09:53:33]
[09:53:33]==2001==vol down====event:5=
[09:53:33]
[09:53:33]app_bt_stream_volumedown set idle volume
[09:53:33]app_bt_stream_volumedown a2dp: 10
[09:53:33]app_bt_stream_volumedown hfp: 12
[09:53:33]btapp_a2dp_report_speak_gain transId:193 a2dp_state:0 streamming:0 report:00
[09:53:33]
[09:53:33]app_bt_key 2048,5
[09:53:33]first blood!
[09:53:33]bt_key_send code:2048 evt:5
[09:53:33]bt_key_handle code:2048 evt:5
[09:53:33]
[09:53:33]==2001==vol down====event:5=
[09:53:33]
[09:53:33]app_bt_stream_volumedown set idle volume
[09:53:33]app_bt_stream_volumedown a2dp: 9
[09:53:33]app_bt_stream_volumedown hfp: 12
[09:53:33]btapp_a2dp_report_speak_gain transId:193 a2dp_state:0 streamming:0 report:00
[09:53:33]
[09:53:34]CPU USAGE: busy=2 light_sleep=50 deep_sleep=48
[09:53:36]*** SYSFREQ top_user=0
[09:53:36]app_battery_handle_process 0,4069
[09:53:36]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:53:36]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:53:36]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:53:44]CPU USAGE: busy=1 light_sleep=0 deep_sleep=99
[09:53:46]*** SYSFREQ top_user=0
[09:53:46]app_battery_handle_process 0,4068
[09:53:46]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:53:46]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:53:46]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:53:54]CPU USAGE: busy=1 light_sleep=0 deep_sleep=99
[09:53:56]*** SYSFREQ top_user=0
[09:53:56]app_battery_handle_process 0,4066
[09:53:56]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:53:56]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:53:56]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:53:57]channelid is 0x41, mux's channelid is 0x41
[09:53:58][TX]
[09:53:58]01 35 0c 05 01 80 00 01 00
[09:53:58] [RX:]
[09:53:58]13 05 01 80 00 01 00
[09:54:04]CPU USAGE: busy=2 light_sleep=63 deep_sleep=35
[09:54:06]*** SYSFREQ top_user=0
[09:54:06]app_battery_handle_process 0,4066
[09:54:06]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:54:06]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:54:06]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:54:14]CPU USAGE: busy=1 light_sleep=99 deep_sleep=0
[09:54:16]*** SYSFREQ top_user=0
[09:54:16]app_battery_handle_process 0,4066
[09:54:16]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:54:16]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:54:16]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:54:22][TX]
[09:54:22]01 35 0c 05 01 80 00 01 00
[09:54:24]CPU USAGE: busy=2 light_sleep=46 deep_sleep=52
[09:54:26]*** SYSFREQ top_user=0
[09:54:26]app_battery_handle_process 0,4066
[09:54:26]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:54:26]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:54:26]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:54:34]CPU USAGE: busy=1 light_sleep=99 deep_sleep=0
[09:54:36]*** SYSFREQ top_user=0
[09:54:36]app_battery_handle_process 0,4066
[09:54:36]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:54:36]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:54:36]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:54:44]CPU USAGE: busy=1 light_sleep=99 deep_sleep=0
[09:54:46]*** SYSFREQ top_user=0
[09:54:46]app_battery_handle_process 0,4068
[09:54:46]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:54:46]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:54:46]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:54:54]CPU USAGE: busy=2 light_sleep=23 deep_sleep=75
[09:54:56]*** SYSFREQ top_user=0
[09:54:56]app_battery_handle_process 0,4068
[09:54:56]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:54:56]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:54:56]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:55:04]CPU USAGE: busy=1 light_sleep=0 deep_sleep=99
[09:55:06]*** SYSFREQ top_user=0
[09:55:06]app_battery_handle_process 0,4068
[09:55:06]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:55:06]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:06]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:55:07][TX]
[09:55:07]01 35 0c 05 01 80 00 01 00
[09:55:07][TX]
[09:55:07]01 35 0c 05 01 80 00 01 00
[09:55:11]channelid is 0x41, mux's channelid is 0x41
[09:55:12][TX]
[09:55:12]01 35 0c 05 01 80 00 01 00
[09:55:12] [RX:]
[09:55:12]13 05 01 80 00 01 00
[09:55:14]CPU USAGE: busy=2 light_sleep=74 deep_sleep=24
[09:55:16]*** SYSFREQ top_user=0
[09:55:16]app_battery_handle_process 0,4068
[09:55:16]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:55:16]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:16]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:1 remDev:2000e404
[09:55:16] [RX:]
[09:55:16]14 06 00 80 00 00 00 00
[09:55:16]channelid is 0x41, mux's channelid is 0x41
[09:55:16]::HF_EVENT_SERVICE_DISCONNECTED Chan_id:0, reason=13
[09:55:16]
[09:55:16]STREAM MANAGE bt_media_stop type= 4,device id = 0
[09:55:16]bt_media_stop media_active = 0,curr_active_media = 0
[09:55:16]set current media = 0
[09:55:16]bt_media_stop end media_active = 0,curr_active_media = 0
[09:55:16]app_bt_stream_close prev:9 cur:0
[09:55:16]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:16]app_bt_profile_active_store_ptr_get hfp_act:1 hsp_act:0 a2dp_act:0x0 codec_type=0
[09:55:16]app_bt_profile_connect_manager_hf HF_EVENT_SERVICE_DISCONNECTED discReason:19/0
[09:55:16]
[09:55:16]===del_cur_bt_adr()===
[09:55:16]
[09:55:16]
[09:55:16]=====del adr:============
[09:55:16]
[09:55:16]c cf 87 3 42 fc
[09:55:16] [RX:]
[09:55:16]13 05 01 80 00 01 00
[09:55:16]app_voice_report 10
[09:55:16]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1c
[09:55:16]bt_media_start media_active = 2,curr_active_media = 0
[09:55:16]set current media = 2
[09:55:16]app_bt_stream_open prev:9 cur:7 freq:0
[09:55:16]app_play_audio_onoff work:0 op:1 aud_id:28
[09:55:16]app_overlay_select id:1:8
[09:55:16][af_stream_open] id = 0, stream = 0
[09:55:16]codec_int_open: running=0
[09:55:16]trig codec open
[09:55:16]codec_hw_open
[09:55:16]codec_hw_open: codec_hw_state=0
[09:55:16]codec_int_stream_open: stream=0
[09:55:16]codec_int_stream_setup: stream=0
[09:55:16][sample_rate]old = 16000, new = 16000
[09:55:16][codec_int_stream_setup]stream = 0, set_flag = 2
[09:55:16][hal_codec_setup_stream] playback sample_rate=16000
[09:55:16]codec_int_stream_start: stream=0
[09:55:16]codec_hw_start: stream=0
[09:55:16]::SPP_EVENT_REMDEV_DISCONNECTED 1
[09:55:16]
[09:55:16]app_spp_close_read_thread 283
[09:55:16]
[09:55:16]appm_start_advertising state: 2
[09:55:16]03 03 c0 ff 0b ff ee 03 b8 11 7a b6 10 2c 01 00 0a 08 53 4d 41 52 54 20 42 4c 45
[09:55:16]appm_restart_advertising!!!!
[09:55:16]app_linkplay_stop_stream_status:The connected app is not IOS!!!
[09:55:16]SV disconnected.
[09:55:16]channelid is 0x41, mux's channelid is 0x41
[09:55:16]::SPP_EVENT_REMDEV_DISCONNECTED 1
[09:55:16]
[09:55:16]app_spp_close_read_thread 283
[09:55:16]
[09:55:16]appm_start_advertising state: 2
[09:55:16]03 03 c0 ff 0b ff ee 03 b8 11 7a b6 10 2c 01 00 0a 08 53 4d 41 52 54 20 42 4c 45
[09:55:16]appm_restart_advertising!!!!
[09:55:16]app_linkplay_stop_stream_status:The connected app is not IOS!!!
[09:55:16]SV disconnected.
[09:55:17] [RX:]
[09:55:17]13 05 01 80 00 01 00
[09:55:17][TX]
[09:55:17]01 35 0c 05 01 80 00 03 00
[09:55:17]channelid is 0x41, mux's channelid is 0x41
[09:55:17] [RX:]
[09:55:17]13 05 01 80 00 01 00
[09:55:17]channelid is 0x41, mux's channelid is 0x41
[09:55:17][TX]
[09:55:17]01 06 04 03 80 00 13
[09:55:17] [RX:]
[09:55:17]0f 04 00 05 06 04
[09:55:17] [RX:]
[09:55:17]13 05 01 80 00 01 00
[09:55:17] [RX:]
[09:55:17]05 04 00 80 00 16
[09:55:17]app_bt_golbal_handle evt = 6
[09:55:17]DISCONNECT evt = 6 encryptState:2
[09:55:17]DISCONNECT activeCons:0
[09:55:17]app_bt_send_request: 0
[09:55:17]
[09:55:17]app_bt_handle_process: 0
[09:55:17]
[09:55:17]app_bt_accessmode_timehandler accMode=3
[09:55:17]app_bt_mail_process src_thread:0x2000332c request_id:17 enter
[09:55:17]app_bt_mail_process request_id:17 :status:0 exit
[09:55:17]app_bt_mail_process src_thread:0x2000332c request_id:2 enter
[09:55:17]app_bt_mail_process request_id:2 :status:0 exit
[09:55:17]app_bt_mail_process src_thread:0x2000332c request_id:8 enter
[09:55:17]app_bt_mail_process request_id:8 :status:0 exit
[09:55:17]app_bt_mail_process src_thread:0x200035f4 request_id:5 enter
[09:55:17]!!!ME_SetAccessibleMode MEC(accOp).mode = 3, mode = 3
[09:55:17]
[09:55:17]app_bt_mail_process request_id:5 :status:2 exit
[09:55:17][TX]
[09:55:17]01 1a 0c 01 03
[09:55:17] [RX:]
[09:55:17]0e 04 05 1a 0c 00
[09:55:17]app_bt_golbal_handle evt = 10
[09:55:17]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x3
[09:55:17]app_status_indication_set 7
[09:55:17]app_voice_report 7
[09:55:17]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = d
[09:55:17]bt_media_start media_active = 2,curr_active_media = 2
[09:55:17]set current media = 2
[09:55:18]cache underflow
[09:55:18]media_sbc_decoder underflow len:256
[09:55:18]
[09:55:18]app_play_sbc_more_data-->need close, length:6240 len:256 l:0
[09:55:18]app_bt_stream_close prev:7 cur:7
[09:55:18]app_play_audio_onoff work:1 op:0 aud_id:0
[09:55:18]codec_int_stream_stop: stream=0
[09:55:18]codec_hw_stop: stream=0
[09:55:18]codec_int_stream_close: stream=0
[09:55:18]codec_int_close: type=0 running=1
[09:55:18]trig codec close
[09:55:18]codec_hw_close
[09:55:18]codec_hw_close: codec_hw_state=2
[09:55:18]app_bt_stream_open prev:9 cur:7 freq:0
[09:55:18]app_play_audio_onoff work:0 op:1 aud_id:13
[09:55:18]app_overlay_select id:1:8
[09:55:18][af_stream_open] id = 0, stream = 0
[09:55:18]codec_int_open: running=0
[09:55:18]trig codec open
[09:55:18]codec_hw_open
[09:55:18]codec_hw_open: codec_hw_state=1
[09:55:18]codec_int_stream_open: stream=0
[09:55:18]codec_int_stream_setup: stream=0
[09:55:18][sample_rate]old = 16000, new = 16000
[09:55:18][codec_int_stream_setup]stream = 0, set_flag = 2
[09:55:18][hal_codec_setup_stream] playback sample_rate=16000
[09:55:18]codec_int_stream_start: stream=0
[09:55:18]codec_hw_start: stream=0
[09:55:18]cache underflow
[09:55:18]media_sbc_decoder underflow len:256
[09:55:18]
[09:55:18]app_play_sbc_more_data-->need close, length:3120 len:256 l:0
[09:55:18]app_bt_stream_close prev:7 cur:7
[09:55:18]app_play_audio_onoff work:1 op:0 aud_id:0
[09:55:18]codec_int_stream_stop: stream=0
[09:55:18]codec_hw_stop: stream=0
[09:55:18]codec_int_stream_close: stream=0
[09:55:18]codec_int_close: type=0 running=1
[09:55:18]trig codec close
[09:55:18]codec_hw_close
[09:55:18]codec_hw_close: codec_hw_state=2
[09:55:18]STREAM MANAGE bt_media_stop type= 2,device id = 0
[09:55:18]bt_media_stop media_active = 2,curr_active_media = 2
[09:55:18]set current media = 0
[09:55:18]bt_media_stop end media_active = 0,curr_active_media = 0
[09:55:23]codec_int_close: type=1 running=0
[09:55:23]codec_hw_close
[09:55:23]codec_hw_close: codec_hw_state=1
[09:55:24]CPU USAGE: busy=3 light_sleep=97 deep_sleep=0
[09:55:26]*** SYSFREQ top_user=0
[09:55:26]app_battery_handle_process 0,4068
[09:55:26]checker: id:0 state:0 mode:0 role:1 cmghdl:0 sniffInterva:499/8704 IsAudioUp:18
[09:55:26]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:26]remDev:2000e404 a2dp State:0 remDev:0 hf_channel Connected:0 remDev:2000e404
[09:55:28]app_bt_accessmode_timehandler accMode=2
[09:55:28]app_bt_mail_process src_thread:0x20003774 request_id:5 enter
[09:55:28]!!!ME_SetAccessibleMode MEC(accOp).mode = 2, mode = 2
[09:55:28]
[09:55:28]app_bt_mail_process request_id:5 :status:2 exit
[09:55:28][TX]
[09:55:28]01 1a 0c 01 02
[09:55:28] [RX:]
[09:55:28]0e 04 05 1a 0c 00
[09:55:28]app_bt_golbal_handle evt = 10
[09:55:28]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x2
[09:55:32]app_bt_accessmode_timehandler accMode=3
[09:55:32]app_bt_mail_process src_thread:0x20003774 request_id:5 enter
[09:55:32]!!!ME_SetAccessibleMode MEC(accOp).mode = 3, mode = 3
[09:55:32]
[09:55:32]app_bt_mail_process request_id:5 :status:2 exit
[09:55:32][TX]
[09:55:32]01 1a 0c 01 03
[09:55:32] [RX:]
[09:55:32]0e 04 05 1a 0c 00
[09:55:32]app_bt_golbal_handle evt = 10
[09:55:32]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x3
[09:55:32] [RX:]
[09:55:32]04 0a 0c cf 87 03 42 fc 0c 02 5a 01
[09:55:32][TX]
[09:55:32]01 09 04 07 0c cf 87 03 42 fc 01
[09:55:32] [RX:]
[09:55:32]0f 04 00 05 09 04
[09:55:32] [RX:]
[09:55:32]03 0b 00 80 00 0c cf 87 03 42 fc 01 00
[09:55:32]app_bt_golbal_handle evt = 4
[09:55:32]CONNECT_IND/CNF evt:4 errCode:0x0 newRole:4 activeCons:1
[09:55:32]CONNECT_IND disable role 2000e404
[09:55:32]
[09:55:32]BTEVENT_LINK_CONNECT_IND/CNF activeCons:1
[09:55:32]app_bt_send_request: 0
[09:55:32]
[09:55:32]app_bt_handle_process: 0
[09:55:32]
[09:55:32]CONNECT_IND sniff info 2000e404
[09:55:32]
[09:55:32][TX]
[09:55:32]01 09 08 02 80 00
[09:55:32] [RX:]
[09:55:32]0e 07 05 09 08 00 80 00 01
[09:55:32]app_bt_accessmode_set access_mode=0
[09:55:32]app_status_indication_set 2
[09:55:32]app_bt_mail_process src_thread:0x2000332c request_id:6 enter
[09:55:32]app_bt_mail_process request_id:6 :status:19 exit
[09:55:32]app_bt_golbal_handle evt = 14
[09:55:32]ROLE_CHANGE eType:0xe errCode:0x0 newRole:1 activeCons:1
[09:55:32][TX]
[09:55:32]01 0d 08 04 80 00 04 00
[09:55:32] [RX:]
[09:55:32]0e 06 05 0d 08 00 80 00
[09:55:32]app_bt_mail_process src_thread:0x2000332c request_id:2 enter
[09:55:32]app_bt_mail_process request_id:2 :status:0 exit
[09:55:32][TX]
[09:55:32]01 1d 04 02 80 00
[09:55:32]app_bt_mail_process src_thread:0x2000332c request_id:8 enter
[09:55:32]app_bt_mail_process request_id:8 :status:0 exit
[09:55:32]app_bt_mail_process src_thread:0x200035f4 request_id:5 enter
[09:55:32]!!!ME_SetAccessibleMode MEC(accOp).mode = 0, mode = 0
[09:55:32]
[09:55:32]app_bt_mail_process request_id:5 :status:2 exit
[09:55:32] [RX:]
[09:55:32]38 04 80 00 40 1f
[09:55:32] [RX:]
[09:55:32]0f 04 00 05 1d 04
[09:55:32] [RX:]
[09:55:32]0c 08 00 80 00 08 1d 00 d3 07
[09:55:32][TX]
[09:55:32]01 1b 04 02 80 00
[09:55:32] [RX:]
[09:55:32]0f 04 00 05 1b 04
[09:55:32] [RX:]
[09:55:32]0b 0b 00 80 00 ff fe 8f fe d8 3f 5b 87
[09:55:32][TX]
[09:55:32]01 1a 0c 01 00
[09:55:32] [RX:]
[09:55:32]1b 03 80 00 05
[09:55:32] [RX:]
[09:55:32]0e 04 05 1a 0c 00
[09:55:32] [RX:]
[09:55:32]13 05 01 80 00 01 00
[09:55:32]app_bt_golbal_handle evt = 10
[09:55:32]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x0
[09:55:32][TX]
[09:55:32]01 1c 04 03 80 00 01
[09:55:32] [RX:]
[09:55:32]0f 04 00 05 1c 04
[09:55:32] [RX:]
[09:55:32]23 0d 00 80 00 00 00 01 00 00 00 00 00 00 00
[09:55:32] [RX:]
[09:55:32]32 09 0c cf 87 03 42 fc 01 00 03
[09:55:32] [RX:]
[09:55:32]31 06 0c cf 87 03 42 fc
[09:55:32][TX]
[09:55:32]01 2b 04 09 0c cf 87 03 42 fc 03 00 02
[09:55:32] [RX:]
[09:55:32]0e 0a 05 2b 04 00 0c cf 87 03 42 fc
[09:55:32][TX]
[09:55:32]01 35 0c 05 01 80 00 01 00
[09:55:33] [RX:]
[09:55:33]33 0a 0c cf 87 03 42 fc 25 da 07 00
[09:55:33][TX]
[09:55:33]01 2c 04 06 0c cf 87 03 42 fc
[09:55:33] [RX:]
[09:55:33]0e 0a 05 2c 04 00 0c cf 87 03 42 fc
[09:55:33] [RX:]
[09:55:33]36 07 00 0c cf 87 03 42 fc
[09:55:33] [RX:]
[09:55:33]18 17 0c cf 87 03 42 fc 0c 66 63 18 13 4c ee 88 f0 6f b6 be 2a e2 e8 fa 04
[09:55:33]
[09:55:33]===nv_record_ddbrec_add()=======
[09:55:33]
[09:55:33]::BTEVENT_PAIRING_COMPLETE keyType:4
[09:55:33]
[09:55:33]app_voice_report 13
[09:55:33]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = f
[09:55:33]bt_media_start media_active = 2,curr_active_media = 0
[09:55:33]set current media = 2
[09:55:33]app_bt_stream_open prev:9 cur:7 freq:0
[09:55:33]app_play_audio_onoff work:0 op:1 aud_id:15
[09:55:33]app_overlay_select id:1:8
[09:55:33][af_stream_open] id = 0, stream = 0
[09:55:33]codec_int_open: running=0
[09:55:33]trig codec open
[09:55:33]codec_hw_open
[09:55:33]codec_hw_open: codec_hw_state=0
[09:55:33]codec_int_stream_open: stream=0
[09:55:33]codec_int_stream_setup: stream=0
[09:55:33][sample_rate]old = 16000, new = 16000
[09:55:33][codec_int_stream_setup]stream = 0, set_flag = 2
[09:55:33][hal_codec_setup_stream] playback sample_rate=16000
[09:55:33]codec_int_stream_start: stream=0
[09:55:33]codec_hw_start: stream=0
[09:55:33] [RX:]
[09:55:33]13 05 01 80 00 01 00
[09:55:33] [RX:]
[09:55:33]13 05 01 80 00 01 00
[09:55:33][TX]
[09:55:33]01 35 0c 05 01 80 00 03 00
[09:55:33] [RX:]
[09:55:33]13 05 01 80 00 01 00
[09:55:33] [RX:]
[09:55:33]13 05 01 80 00 01 00
[09:55:33] [RX:]
[09:55:33]13 05 01 80 00 01 00
[09:55:33][TX]
[09:55:33]01 35 0c 05 01 80 00 03 00
[09:55:33] [RX:]
[09:55:33]13 05 01 80 00 01 00
[09:55:33] [RX:]
[09:55:33]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34][TX]
[09:55:34]01 35 0c 05 01 80 00 03 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34][TX]
[09:55:34]01 35 0c 05 01 80 00 03 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34][TX]
[09:55:34]01 35 0c 05 01 80 00 02 00
[09:55:34]CPU USAGE: busy=3 light_sleep=97 deep_sleep=0
[09:55:34]cache underflow
[09:55:34]media_sbc_decoder underflow len:256
[09:55:34]
[09:55:34]app_play_sbc_more_data-->need close, length:5520 len:256 l:0
[09:55:34]app_bt_stream_close prev:7 cur:7
[09:55:34]app_play_audio_onoff work:1 op:0 aud_id:0
[09:55:34]codec_int_stream_stop: stream=0
[09:55:34]codec_hw_stop: stream=0
[09:55:34]codec_int_stream_close: stream=0
[09:55:34]codec_int_close: type=0 running=1
[09:55:34]trig codec close
[09:55:34]codec_hw_close
[09:55:34]codec_hw_close: codec_hw_state=2
[09:55:34]STREAM MANAGE bt_media_stop type= 2,device id = 0
[09:55:34]bt_media_stop media_active = 2,curr_active_media = 2
[09:55:34]set current media = 0
[09:55:34]bt_media_stop end media_active = 0,curr_active_media = 0
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34][TX]
[09:55:34]01 35 0c 05 01 80 00 03 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]08 04 00 80 00 01
[09:55:34][TX]
[09:55:34]01 35 0c 05 01 80 00 03 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34] [RX:]
[09:55:34]13 05 01 80 00 01 00
[09:55:34]channelid is 0x40, mux's channelid is 0x40
[09:55:34][TX]
[09:55:34]01 35 0c 05 01 80 00 03 00
[09:55:34]channelid is 0x40, mux's channelid is 0x40
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]!!!CMGR_CreateDataLink Handler->flags = 0
[09:55:35]
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]!!!HfIndicatorCallback hf.ind_test.num:7
[09:55:35]
[09:55:35]::HF_EVENT_CALL_IND chan_id:0, call:0
[09:55:35]
[09:55:35]app_media_update_media
[09:55:35]skip idle
[09:55:35]::HF_EVENT_CALLSETUP_IND chan_id:0, callSetup = 0
[09:55:35]
[09:55:35]::HF_EVENT_CALLHELD_IND chan_id:0 0 HELD_STATUS = 0
[09:55:35]
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35]!!!CMGR_CreateDataLink Handler->flags = 0
[09:55:35]
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]Allocate currentDevice 0x20013ccc
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]status 0
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]::A2DP_EVENT_AVDTP_CONNECT 23 st = 0x2000c6ac
[09:55:35]
[09:55:35]::A2DP_EVENT_AVDTP_CONNECT 23 st = 0x2000c80c
[09:55:35]
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35]/,version=c0551f9
[09:55:35]/,version=c0551f9
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]::HF_EVENT_SERVICE_CONNECTED Chan_id:0
[09:55:35]
[09:55:35]hfp get vol raw:10 loc:12
[09:55:35]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:35]app_bt_profile_active_store_ptr_get hfp_act:0 hsp_act:0 a2dp_act:0x0 codec_type=0
[09:55:35]app_bt_profile_connect_manager_hf HF_EVENT_SERVICE_CONNECTED
[09:55:35]
[09:55:35]===save_cur_bt_adr()===
[09:55:35]
[09:55:35]app_voice_report 9
[09:55:35]STREAM MANAGE bt_media_start type= 2,device id = 0,media_id = 1b
[09:55:35]bt_media_start media_active = 2,curr_active_media = 0
[09:55:35]set current media = 2
[09:55:35]app_bt_stream_open prev:9 cur:7 freq:0
[09:55:35]app_play_audio_onoff work:0 op:1 aud_id:27
[09:55:35]app_overlay_select id:1:8
[09:55:35][af_stream_open] id = 0, stream = 0
[09:55:35]codec_int_open: running=0
[09:55:35]trig codec open
[09:55:35]codec_hw_open
[09:55:35]codec_hw_open: codec_hw_state=1
[09:55:35]codec_int_stream_open: stream=0
[09:55:35]codec_int_stream_setup: stream=0
[09:55:35][sample_rate]old = 16000, new = 16000
[09:55:35][codec_int_stream_setup]stream = 0, set_flag = 2
[09:55:35][hal_codec_setup_stream] playback sample_rate=16000
[09:55:35]codec_int_stream_start: stream=0
[09:55:35]codec_hw_start: stream=0
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c29c
[09:55:35]
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35][TX]
[09:55:35]01 35 0c 05 01 80 00 03 00
[09:55:35]::SPP_EVENT_REMDEV_CONNECTED 0
[09:55:35]
[09:55:35]app_spp_create_read_thread 277
[09:55:35]
[09:55:35]appm_stop_advertising :794
[09:55:35]
[09:55:35]===1=====
[09:55:35]
[09:55:35]
[09:55:35]=====is spp apps=====
[09:55:35]
[09:55:35]bes_ble_stop_advertising!!!!
[09:55:35]SV connection event, Conn type 0x1, path type 0x0
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35]::A2DP_EVENT_STREAM_OPEN_IND 1
[09:55:35]
[09:55:35][TX]
[09:55:35]01 0a 20 01 00
[09:55:35] [RX:]
[09:55:35]0e 04 05 0a 20 00
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35] [RX:]
[09:55:35]13 05 01 80 00 01 00
[09:55:35]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c314
[09:55:35]
[09:55:35]channelid is 0x40, mux's channelid is 0x40
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36]channelid is 0x40, mux's channelid is 0x40
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36]channelid is 0x40, mux's channelid is 0x40
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36]::HF_EVENT_COMMAND_COMPLETE chan_id:0 2000c2e0
[09:55:36]
[09:55:36][linkplay_spp_cmd_received]Receive data: len:5
[09:55:36]0x14 0x80 0x01 0x00 0x00
[09:55:36]currentPosition: 0, currDataLen:5
[09:55:36]Receive data:
[09:55:36]0x14 0x80 0x01 0x00 0x00
[09:55:36]App_To_Device_UpdateAlexaState:notifications_state=0
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36][linkplay_spp_cmd_received]Receive data: len:14
[09:55:36]0x06 0x80 0x00 0x00 0x17 0x80 0x01 0x00 0x01 0x15 0x80 0x01 0x00 0x00
[09:55:36]currentPosition: 0, currDataLen:4
[09:55:36]Receive data:
[09:55:36]0x06 0x80 0x00 0x00
[09:55:36]app_sv_get_device_information_handler.
[09:55:36]Send cmd:
[09:55:36]00 80 4d 00 06 80 00 00 47 00 7b 22 44 65 76 69 63 65 4e 61 6d 65 22 3a 22 42 45 53 32 30 30 30 49 4c 22 2c 22 4d 61 69 6e 56 65 72 73 69 6f 6e 22 3a 22 56 30 2e 30 2e 38 22 2c 22 53 75 62 56 65 72 73 69 6f 6e 22 3a 22 56 30 2e 30 2e 30 22 7d
[09:55:36]dataLen 81 offsetToFillSppTxData 162 occupiedSppTxBufSize 81
[09:55:36]currentPosition: 4, currDataLen:5
[09:55:36]Receive data:
[09:55:36]0x17 0x80 0x01 0x00 0x01
[09:55:36]currentPosition: 9, currDataLen:5
[09:55:36]Receive data:
[09:55:36]0x15 0x80 0x01 0x00 0x00
[09:55:36]App_To_Device_UpdateAlexaState:account_state=0
[09:55:36][linkplay_spp_cmd_received]Receive data: len:5
[09:55:36]0x07 0x80 0x01 0x00 0x00
[09:55:36]currentPosition: 0, currDataLen:5
[09:55:36]Receive data:
[09:55:36]0x07 0x80 0x01 0x00 0x00
[09:55:36]linkplay_resume_play_timer_init:The connected app is not IOS!!!
[09:55:36]App_To_Device_UpdateAlexaState: speech_state=0
[09:55:36]channelid is 0x40, mux's channelid is 0x40
[09:55:36]occupiedSppTxBufSize 0
[09:55:36]Data sent out
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36][linkplay_spp_cmd_received]Receive data: len:5
[09:55:36]0x16 0x80 0x01 0x00 0x02
[09:55:36]currentPosition: 0, currDataLen:5
[09:55:36]Receive data:
[09:55:36]0x16 0x80 0x01 0x00 0x02
[09:55:36]App_To_Device_UpdateAlexaState:e_state 2 is error!!!
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36]::A2DP_EVENT_STREAM_OPEN stream_id:0, sample_rate codec.elements 0x21
[09:55:36]
[09:55:36]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36]app_bt_stream_volume_ptr_update a2dp_vol:12 hfp_vol:12 ptr:0x20000378
[09:55:36]codecType 0x0
[09:55:36]
[09:55:36]app_bt_device.sample_rate::elements[0] 33
[09:55:36]
[09:55:36]!!!CMGR_CreateDataLink Handler->flags = 0
[09:55:36]
[09:55:36]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:36]app_bt_profile_active_store_ptr_get hfp_act:1 hsp_act:0 a2dp_act:0x0 codec_type=0
[09:55:36]app_bt_profile_connect_manager_a2dp A2DP_EVENT_STREAM_OPEN,codec type=0
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36]avrcp_callback : chnl 0x2000c978, Parms 0x20008d00
[09:55:36]
[09:55:36]::Parms->event 1
[09:55:36]
[09:55:36]::AVRCP_EVENT_CONNECT 104
[09:55:36]
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36]app_bt_accessmode_set access_mode=0
[09:55:36]app_bt_mail_process src_thread:0x2000332c request_id:5 enter
[09:55:36]!!!ME_SetAccessibleMode MEC(accOp).mode = 0, mode = 0
[09:55:36]
[09:55:36]app_bt_golbal_handle evt = 10
[09:55:36]ACCESSIBLE_CHANGE evt:10 errCode:0x0 aMode=0x0
[09:55:36]app_bt_mail_process request_id:5 :status:2 exit
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36][linkplay_spp_cmd_received]Receive data: len:5
[09:55:36]0x0c 0x80 0x01 0x00 0x0a
[09:55:36]currentPosition: 0, currDataLen:5
[09:55:36]Receive data:
[09:55:36]0x0c 0x80 0x01 0x00 0x0a
[09:55:36]app_sv_sync_volume_handler.
[09:55:36]app_bt_stream_volumeset vol=11
[09:55:36][TX]
[09:55:36]01 35 0c 05 01 80 00 03 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36] [RX:]
[09:55:36]13 05 01 80 00 01 00
[09:55:36]*** SYSFREQ user=3 freq=4
[09:55:36]*** SYSFREQ top_user=3
[09:55:36]app_battery_handle_process 0,4066
[09:55:36]checker: id:0 state:3 mode:0 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:55:36]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:36]remDev:2000e404 a2dp State:2 remDev:2000e404 hf_channel Connected:1 remDev:2000e404
[09:55:36]cache underflow
[09:55:36]media_sbc_decoder underflow len:256
[09:55:36]
[09:55:36]app_play_sbc_more_data-->need close, length:5280 len:256 l:0
[09:55:36]app_bt_stream_close prev:7 cur:7
[09:55:36]app_play_audio_onoff work:1 op:0 aud_id:0
[09:55:36]codec_int_stream_stop: stream=0
[09:55:36]codec_hw_stop: stream=0
[09:55:36]codec_int_stream_close: stream=0
[09:55:36]codec_int_close: type=0 running=1
[09:55:36]trig codec close
[09:55:36]codec_hw_close
[09:55:36]codec_hw_close: codec_hw_state=2
[09:55:36]STREAM MANAGE bt_media_stop type= 2,device id = 0
[09:55:36]bt_media_stop media_active = 2,curr_active_media = 2
[09:55:36]set current media = 0
[09:55:36]bt_media_stop end media_active = 0,curr_active_media = 0
[09:55:41]codec_int_close: type=1 running=0
[09:55:41]codec_hw_close
[09:55:41]codec_hw_close: codec_hw_state=1
[09:55:43] [RX:]
[09:55:43]14 06 00 80 00 02 20 03
[09:55:44]CPU USAGE: busy=7 light_sleep=93 deep_sleep=0
[09:55:45]hal_pwrkey_irqhandler: 00000001
[09:55:45]
[09:55:45]====pwr key (1,1,0)=(5006358)==
[09:55:45]
[09:55:45]app_bt_key 1,5
[09:55:45]first blood!
[09:55:45]bt_key_send code:1 evt:5
[09:55:45]bt_key_handle code:1 evt:5
[09:55:45]
[09:55:45]==powerkey==play pause func=====
[09:55:45]
[09:55:45]lp_alexa_key_play_deal:The connected app is not IOS!!!
[09:55:45]avrcp_key = AVRCP_KEY_PLAY
[09:55:46]avrcp_callback : chnl 0x2000c978, Parms 0x20008d10
[09:55:46]
[09:55:46]::Parms->event 14
[09:55:46]
[09:55:46]::AVRCP_EVENT_PANEL_CNF f1,44,0
[09:55:46]avrcp_callback : chnl 0x2000c978, Parms 0x20008ca0
[09:55:46]
[09:55:46]::Parms->event 14
[09:55:46]
[09:55:46]::AVRCP_EVENT_PANEL_CNF f0,44,0
[09:55:46]*** SYSFREQ top_user=0
[09:55:46]app_battery_handle_process 0,4064
[09:55:46]checker: id:0 state:3 mode:2 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:55:46]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:46]remDev:2000e404 a2dp State:2 remDev:2000e404 hf_channel Connected:1 remDev:2000e404
[09:55:46] [RX:]
[09:55:46]14 06 00 80 00 00 00 00
[09:55:46] [RX:]
[09:55:46]13 05 01 80 00 01 00
[09:55:46]avrcp_callback : chnl 0x2000c978, Parms 0x20008c88
[09:55:46]
[09:55:46]::Parms->event 5
[09:55:46]
[09:55:46]::AVRCP_EVENT_ADV_RESPONSE op=0,status=0
[09:55:46]
[09:55:46] [RX:]
[09:55:46]0d 15 00 80 00 00 01 d4 8e 01 00 00 00 00 00 10 27 00 00 ff ff ff ff
[09:55:46]::A2DP_EVENT_STREAM_START_IND 0 stream_id:0 0 0
[09:55:46]
[09:55:46]!!!ME_StopSniff remDev2000e404
[09:55:46]
[09:55:46]::A2DP_EVENT_STREAM_STARTED 0 stream_id:0 0 1
[09:55:46]
[09:55:46]STREAM MANAGE bt_media_start type= 1,device id = 0,media_id = 21
[09:55:46]bt_media_start media_active = 1,curr_active_media = 0
[09:55:46]app_audio_manager_switch_a2dp switch_a2dp to id:0
[09:55:46] [RX:]
[09:55:46]13 05 01 80 00 01 00
[09:55:46]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:46]app_bt_stream_volume_ptr_update a2dp_vol:12 hfp_vol:12 ptr:0x20000378
[09:55:46]set current media = 1
[09:55:46]app_bt_stream_setup prev:9 cur:3 sample:32
[09:55:46]app_bt_stream_open prev:9 cur:3 freq:0
[09:55:46]bt_sbc_player work:0 op:0 freq:0 :sample:44100
[09:55:46]
[09:55:46]bt_media_volume_ptr_update_by_mediatype enter
[09:55:46]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:46]app_bt_stream_volume_ptr_update a2dp_vol:12 hfp_vol:12 ptr:0x20000378
[09:55:46]bt_media_volume_ptr_update_by_mediatype exit
[09:55:46]bt_sbc_player: app_sysfreq_req 2
[09:55:46]app_overlay_select id:1:8
[09:55:46]bt_init_xtal_sync
[09:55:46][af_stream_open] id = 0, stream = 0
[09:55:46]codec_int_open: running=0
[09:55:46]trig codec open
[09:55:46]codec_hw_open
[09:55:46]codec_hw_open: codec_hw_state=0
[09:55:46]codec_int_stream_open: stream=0
[09:55:46]codec_int_stream_setup: stream=0
[09:55:46][sample_rate]old = 16000, new = 44100
[09:55:46][vol]old = 0, new = 12
[09:55:46][codec_int_stream_setup]stream = 0, set_flag = 12
[09:55:46][hal_codec_setup_stream] playback sample_rate=44100
[09:55:46]codec_int_stream_start: stream=0
[09:55:46]codec_hw_start: stream=0
[09:55:46]a2dp_audio_more_data cache not ready skip frame 0
[09:55:46]
[09:55:46]cache add:585
[09:55:46]
[09:55:46][TX]
[09:55:46]01 35 0c 05 01 80 00 03 00
[09:55:46]a2dp_audio_more_data cache not ready skip frame 0
[09:55:46]
[09:55:46]cache add:585
[09:55:46]
[09:55:47]a2dp_audio_more_data cache not ready skip frame 0
[09:55:47]
[09:55:47]cache add:585
[09:55:47]
[09:55:47]cache add:585
[09:55:47]
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]cache add:585
[09:55:47]
[09:55:47]bt_xtal_sync: ms=312265 bit_offset=540
[09:55:47]a2dp_audio_more_data cache not ready skip frame 0
[09:55:47]
[09:55:47]a2dp_audio_more_data cache not ready skip frame 0
[09:55:47]
[09:55:47]cache add:585
[09:55:47]
[09:55:47]cache add:585
[09:55:47]
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]bt_xtal_sync: ms=312322 bit_offset=541
[09:55:47]a2dp_audio_more_data cache not ready skip frame 0
[09:55:47]
[09:55:47]cache add:585
[09:55:47]
[09:55:47]cache add:585
[09:55:47]
[09:55:47]cache add:585
[09:55:47]
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]a2dp_audio_more_data cache not ready skip frame 0
[09:55:47]
[09:55:47]cache add:585
[09:55:47]
[09:55:47]a2dp_audio_more_data cache not ready skip frame 0
[09:55:47]
[09:55:47]a2dp_audio_more_data cache not ready skip frame 0
[09:55:47]
[09:55:47]cache add:585
[09:55:47]
[09:55:47]cache ok:7605
[09:55:47]
[09:55:47]bt_xtal_sync: ms=312401 bit_offset=542
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]bt_xtal_sync: ms=312525 bit_offset=544
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]bt_xtal_sync: ms=312618 bit_offset=546
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]bt_xtal_sync: ms=312686 bit_offset=547
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]bt_xtal_sync: ms=312826 bit_offset=549
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]bt_xtal_sync: ms=312931 bit_offset=551
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]bt_xtal_sync: ms=313005 bit_offset=552
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]bt_xtal_sync: ms=313119 bit_offset=554
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:47]bt_xtal_sync: ms=313186 bit_offset=555
[09:55:47][TX]
[09:55:47]01 35 0c 05 01 80 00 03 00
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48]bt_xtal_sync: ms=313358 bit_offset=558
[09:55:48]
[09:55:48]
[09:55:48]Update 26M: diff=14 ms=956 ppb=14631
[09:55:48]
[09:55:48]
[09:55:48]bt_tune_xtal: BAD new_val=118 init_val=45 tune_factor=200
[09:55:48]
[09:55:48]
[09:55:48]tune_ppb=0
[09:55:48]
[09:55:48]
[09:55:48]cache underflow retry:0
[09:55:48]
[09:55:48]a2dp_audio_more_data decode err 3072/4096
[09:55:48]set to APP_AUDIO_CACHE_CACHEING
[09:55:48]cache add:585
[09:55:48]
[09:55:48]bt_xtal_sync: ms=313425 bit_offset=559
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48]cache add:585
[09:55:48]
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48]cache add:585
[09:55:48]
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48]bt_xtal_sync: ms=313516 bit_offset=560
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48]cache add:585
[09:55:48]
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48]cache add:585
[09:55:48]
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48]cache add:585
[09:55:48]
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48]cache add:585
[09:55:48]
[09:55:48]cache add:585
[09:55:48]
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48]cache add:585
[09:55:48]
[09:55:48]bt_xtal_sync: ms=313649 bit_offset=562
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48]cache add:585
[09:55:48]
[09:55:48]cache add:585
[09:55:48]
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48]cache add:585
[09:55:48]
[09:55:48]a2dp_audio_more_data cache not ready skip frame 0
[09:55:48]
[09:55:48]cache ok:7605
[09:55:48]
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48]bt_xtal_sync: ms=313721 bit_offset=563
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48][TX]
[09:55:48]bt_xtal_sync: ms=313830 bit_offset=565
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48]bt_xtal_sync: ms=313906 bit_offset=566
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48]bt_xtal_sync: ms=314010 bit_offset=568
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48]bt_xtal_sync: ms=314048 bit_offset=569
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:48]bt_xtal_sync: ms=314123 bit_offset=570
[09:55:48][TX]
[09:55:48]01 35 0c 05 01 80 00 03 00
[09:55:49]bt_xtal_sync: ms=314244 bit_offset=572
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]cache underflow retry:0
[09:55:49]
[09:55:49]a2dp_audio_more_data decode err 1536/4096
[09:55:49]set to APP_AUDIO_CACHE_CACHEING
[09:55:49]cache add:585
[09:55:49]
[09:55:49]bt_xtal_sync: ms=314315 bit_offset=573
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]cache add:585
[09:55:49]
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]bt_xtal_sync: ms=314380 bit_offset=574
[09:55:49]
[09:55:49]
[09:55:49]Update 26M: diff=13 ms=731 ppb=17778
[09:55:49]
[09:55:49]
[09:55:49]bt_xtal_sync: Bad ppb=17778 last_valid_ppb=14631 last_tune_ppb=0 (1)
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache ok:7605
[09:55:49]
[09:55:49],rssi_monitor[0]:544 ,rssi_monitor[1]: 162 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]bt_xtal_sync: ms=314514 bit_offset=576
[09:55:49]bt_xtal_sync: ms=314548 bit_offset=577
[09:55:49]bt_xtal_sync: ms=314619 bit_offset=578
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]bt_xtal_sync: ms=314676 bit_offset=579
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]bt_xtal_sync: ms=314753 bit_offset=580
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]bt_xtal_sync: ms=314859 bit_offset=582
[09:55:49]cache underflow retry:0
[09:55:49]
[09:55:49]a2dp_audio_more_data decode err 1024/4096
[09:55:49]set to APP_AUDIO_CACHE_CACHEING
[09:55:49]bt_xtal_sync: ms=314924 bit_offset=583
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]cache add:585
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]cache add:585
[09:55:49]
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache add:585
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]bt_xtal_sync: ms=315070 bit_offset=585
[09:55:49]a2dp_audio_more_data cache not ready skip frame 0
[09:55:49]
[09:55:49]cache ok:7605
[09:55:49]
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]bt_xtal_sync: ms=315138 bit_offset=586
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:49]bt_xtal_sync: ms=315228 bit_offset=587
[09:55:49][TX]
[09:55:49]01 35 0c 05 01 80 00 03 00
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50]bt_xtal_sync: ms=315341 bit_offset=589
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50]bt_xtal_sync: ms=315481 bit_offset=592
[09:55:50]
[09:55:50]
[09:55:50]Update 26M: diff=12 ms=862 ppb=13913
[09:55:50]
[09:55:50]
[09:55:50]bt_xtal_sync: Bad ppb=13913 last_valid_ppb=17778 last_tune_ppb=0 (2)
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50]bt_xtal_sync: ms=315648 bit_offset=594
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50]bt_xtal_sync: ms=315711 bit_offset=595
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50]bt_xtal_sync: ms=315838 bit_offset=597
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50]bt_xtal_sync: ms=315920 bit_offset=599
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50]bt_xtal_sync: ms=316011 bit_offset=600
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50]bt_xtal_sync: ms=316063 bit_offset=601
[09:55:50]bt_xtal_sync: ms=316155 bit_offset=602
[09:55:50]cache underflow retry:0
[09:55:50]
[09:55:50]a2dp_audio_more_data decode err 3584/4096
[09:55:50]set to APP_AUDIO_CACHE_CACHEING
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50]cache add:585
[09:55:50]
[09:55:50]a2dp_audio_more_data cache not ready skip frame 0
[09:55:50]
[09:55:50]cache add:585
[09:55:50]
[09:55:50]a2dp_audio_more_data cache not ready skip frame 0
[09:55:50]
[09:55:50]cache add:585
[09:55:50]
[09:55:50][TX]
[09:55:50]01 35 0c 05 01 80 00 03 00
[09:55:50]cache add:585
[09:55:50]
[09:55:50]a2dp_audio_more_data cache not ready skip frame 0
[09:55:50]
[09:55:50]bt_xtal_sync: ms=316238 bit_offset=604
[09:55:50]a2dp_audio_more_data cache not ready skip frame 0
[09:55:50]
[09:55:50]cache add:585
[09:55:50]
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51]cache add:585
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51]cache add:585
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]bt_xtal_sync: ms=316350 bit_offset=605
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]bt_xtal_sync: ms=316390 bit_offset=606
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]bt_xtal_sync: ms=316444 bit_offset=607
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51]cache ok:7605
[09:55:51]
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51]bt_xtal_sync: ms=316494 bit_offset=608
[09:55:51]
[09:55:51]
[09:55:51]Update 26M: diff=12 ms=656 ppb=18286
[09:55:51]
[09:55:51]
[09:55:51]bt_xtal_sync: Bad ppb=18286 last_valid_ppb=13913 last_tune_ppb=0 (3)
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51]bt_xtal_sync: ms=316615 bit_offset=610
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 02 00
[09:55:51]bt_xtal_sync: ms=316741 bit_offset=612
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51],rssi_monitor[0]:336 ,rssi_monitor[1]: 149 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:55:51]
[09:55:51]bt_xtal_sync: ms=316841 bit_offset=613
[09:55:51]bt_xtal_sync: ms=316891 bit_offset=614
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51]bt_xtal_sync: ms=316968 bit_offset=615
[09:55:51]cache underflow retry:0
[09:55:51]
[09:55:51]a2dp_audio_more_data decode err 3072/4096
[09:55:51]set to APP_AUDIO_CACHE_CACHEING
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]bt_xtal_sync: ms=317038 bit_offset=616
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]bt_xtal_sync: ms=317059 bit_offset=617
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51]cache add:585
[09:55:51]
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]bt_xtal_sync: ms=317130 bit_offset=618
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51][TX]
[09:55:51]01 35 0c 05 01 80 00 03 00
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51]bt_xtal_sync: ms=317246 bit_offset=620
[09:55:51]a2dp_audio_more_data cache not ready skip frame 0
[09:55:51]
[09:55:51]cache add:585
[09:55:51]
[09:55:51][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52]cache add:585
[09:55:52]
[09:55:52]a2dp_audio_more_data cache not ready skip frame 0
[09:55:52]
[09:55:52]a2dp_audio_more_data cache not ready skip frame 0
[09:55:52]
[09:55:52]a2dp_audio_more_data cache not ready skip frame 0
[09:55:52]
[09:55:52]bt_xtal_sync: ms=317338 bit_offset=621
[09:55:52]a2dp_audio_more_data cache not ready skip frame 0
[09:55:52]
[09:55:52]cache add:585
[09:55:52]
[09:55:52]cache add:585
[09:55:52]
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52]cache ok:7605
[09:55:52]
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52]bt_xtal_sync: ms=317488 bit_offset=624
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52]bt_xtal_sync: ms=317641 bit_offset=1
[09:55:52]
[09:55:52]
[09:55:52]Update 26M: diff=12 ms=800 ppb=15000
[09:55:52]
[09:55:52]
[09:55:52]bt_xtal_sync: Bad ppb=15000 last_valid_ppb=18286 last_tune_ppb=0 (4)
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52]bt_xtal_sync: ms=317695 bit_offset=2
[09:55:52]bt_xtal_sync: ms=317735 bit_offset=3
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52]bt_xtal_sync: ms=317838 bit_offset=4
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52]bt_xtal_sync: ms=317938 bit_offset=6
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52]bt_xtal_sync: ms=318035 bit_offset=7
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52][TX]
[09:55:52]01 35 0c 05 01 80 00 03 00
[09:55:52]bt_xtal_sync: ms=318174 bit_offset=10
[09:55:53]bt_xtal_sync: ms=318243 bit_offset=11
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53]bt_xtal_sync: ms=318365 bit_offset=13
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53]bt_xtal_sync: ms=318545 bit_offset=15
[09:55:53]
[09:55:53]
[09:55:53]Update 26M: diff=10 ms=707 ppb=14134
[09:55:53]
[09:55:53]
[09:55:53]update_xtal_tune_factor: Offset too small: 0
[09:55:53]
[09:55:53]
[09:55:53]bt_tune_xtal: BAD new_val=116 init_val=45 tune_factor=200
[09:55:53]
[09:55:53]
[09:55:53]tune_ppb=0
[09:55:53]
[09:55:53]
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53]bt_xtal_sync: ms=318600 bit_offset=16
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53]bt_xtal_sync: ms=318725 bit_offset=18
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53]bt_xtal_sync: ms=318856 bit_offset=20
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53]bt_xtal_sync: ms=318898 bit_offset=21
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53]bt_xtal_sync: ms=318973 bit_offset=22
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53]bt_xtal_sync: ms=319075 bit_offset=24
[09:55:53],rssi_monitor[0]:320 ,rssi_monitor[1]: 148 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:55:53]
[09:55:53]bt_xtal_sync: ms=319158 bit_offset=25
[09:55:53][TX]
[09:55:53]01 35 0c 05 01 80 00 03 00
[09:55:53]bt_xtal_sync: ms=319199 bit_offset=26
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54]bt_xtal_sync: ms=319373 bit_offset=29
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54]bt_xtal_sync: ms=319461 bit_offset=30
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54]bt_xtal_sync: ms=319540 bit_offset=31
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54]bt_xtal_sync: ms=319700 bit_offset=34
[09:55:54]
[09:55:54]
[09:55:54]Update 26M: diff=13 ms=843 ppb=15407
[09:55:54]
[09:55:54]
[09:55:54]bt_xtal_sync: Bad ppb=15407 last_valid_ppb=14134 last_tune_ppb=0 (1)
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54]CPU USAGE: busy=7 light_sleep=93 deep_sleep=0
[09:55:54]bt_xtal_sync: ms=319768 bit_offset=35
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54]bt_xtal_sync: ms=319888 bit_offset=37
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54]bt_xtal_sync: ms=320010 bit_offset=39
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54]bt_xtal_sync: ms=320108 bit_offset=40
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:54]bt_xtal_sync: ms=320165 bit_offset=41
[09:55:54][TX]
[09:55:54]01 35 0c 05 01 80 00 03 00
[09:55:55]bt_xtal_sync: ms=320254 bit_offset=42
[09:55:55]bt_xtal_sync: ms=320303 bit_offset=43
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55]bt_xtal_sync: ms=320396 bit_offset=45
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55]bt_xtal_sync: ms=320556 bit_offset=47
[09:55:55]bt_xtal_sync: ms=320609 bit_offset=48
[09:55:55]cache underflow retry:0
[09:55:55]
[09:55:55]a2dp_audio_more_data decode err 512/4096
[09:55:55]set to APP_AUDIO_CACHE_CACHEING
[09:55:55]cache add:585
[09:55:55]
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55]cache add:585
[09:55:55]
[09:55:55]cache add:585
[09:55:55]
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]cache add:585
[09:55:55]
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55]cache add:585
[09:55:55]
[09:55:55]bt_xtal_sync: ms=320648 bit_offset=49
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]cache add:585
[09:55:55]
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]cache add:585
[09:55:55]
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55]bt_xtal_sync: ms=320751 bit_offset=50
[09:55:55]
[09:55:55]
[09:55:55]Update 26M: diff=12 ms=741 ppb=16189
[09:55:55]
[09:55:55]
[09:55:55]update_xtal_tune_factor: Offset too small: 0
[09:55:55]
[09:55:55]
[09:55:55]bt_tune_xtal: BAD new_val=126 init_val=45 tune_factor=200
[09:55:55]
[09:55:55]
[09:55:55]tune_ppb=0
[09:55:55]
[09:55:55]
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]cache add:585
[09:55:55]
[09:55:55]cache add:585
[09:55:55]
[09:55:55]cache add:585
[09:55:55]
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55]cache add:585
[09:55:55]
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]bt_xtal_sync: ms=320814 bit_offset=51
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]cache add:585
[09:55:55]
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]a2dp_audio_more_data cache not ready skip frame 0
[09:55:55]
[09:55:55]cache ok:7605
[09:55:55]
[09:55:55]bt_xtal_sync: ms=320896 bit_offset=53
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55]bt_xtal_sync: ms=320998 bit_offset=54
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55]bt_xtal_sync: ms=321076 bit_offset=55
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:55]bt_xtal_sync: ms=321204 bit_offset=57
[09:55:55][TX]
[09:55:55]01 35 0c 05 01 80 00 03 00
[09:55:56]bt_xtal_sync: ms=321258 bit_offset=58
[09:55:56]cache underflow retry:0
[09:55:56]
[09:55:56]a2dp_audio_more_data decode err 1536/4096
[09:55:56]set to APP_AUDIO_CACHE_CACHEING
[09:55:56]cache add:585
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 03 00
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56]bt_xtal_sync: ms=321343 bit_offset=59
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 03 00
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56]bt_xtal_sync: ms=321411 bit_offset=61
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 03 00
[09:55:56]cache add:585
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56],rssi_monitor[0]:417 ,rssi_monitor[1]: 166 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]bt_xtal_sync: ms=321508 bit_offset=62
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 03 00
[09:55:56]cache add:585
[09:55:56]
[09:55:56]bt_xtal_sync: ms=321564 bit_offset=63
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 03 00
[09:55:56]cache ok:7605
[09:55:56]
[09:55:56]bt_xtal_sync: ms=321635 bit_offset=64
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 03 00
[09:55:56]bt_xtal_sync: ms=321725 bit_offset=66
[09:55:56]
[09:55:56]
[09:55:56]Update 26M: diff=12 ms=727 ppb=16495
[09:55:56]
[09:55:56]
[09:55:56]update_xtal_tune_factor: Offset too small: 0
[09:55:56]
[09:55:56]
[09:55:56]bt_tune_xtal: BAD new_val=127 init_val=45 tune_factor=200
[09:55:56]
[09:55:56]
[09:55:56]tune_ppb=0
[09:55:56]
[09:55:56]
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 02 00
[09:55:56]bt_xtal_sync: ms=321818 bit_offset=67
[09:55:56]cache underflow retry:0
[09:55:56]
[09:55:56]a2dp_audio_more_data decode err 2560/4096
[09:55:56]set to APP_AUDIO_CACHE_CACHEING
[09:55:56]cache add:585
[09:55:56]
[09:55:56]bt_xtal_sync: ms=321855 bit_offset=68
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 03 00
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]bt_xtal_sync: ms=321973 bit_offset=70
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 03 00
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 03 00
[09:55:56]bt_xtal_sync: ms=322113 bit_offset=72
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56]bt_xtal_sync: ms=322153 bit_offset=73
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:56][TX]
[09:55:56]01 35 0c 05 01 80 00 03 00
[09:55:56]cache add:585
[09:55:56]
[09:55:56]bt_xtal_sync: ms=322225 bit_offset=74
[09:55:56]a2dp_audio_more_data cache not ready skip frame 0
[09:55:56]
[09:55:56]cache add:585
[09:55:56]
[09:55:57]cache ok:7605
[09:55:57]
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]*** SYSFREQ user=3 freq=2
[09:55:57]*** SYSFREQ top_user=3
[09:55:57]app_battery_handle_process 0,4060
[09:55:57]checker: id:0 state:3 mode:0 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:55:57]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:55:57]remDev:2000e404 a2dp State:3 remDev:2000e404 hf_channel Connected:1 remDev:2000e404
[09:55:57]bt_xtal_sync: ms=322358 bit_offset=76
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 01 00
[09:55:57]bt_xtal_sync: ms=322416 bit_offset=77
[09:55:57]bt_xtal_sync: ms=322473 bit_offset=78
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]bt_xtal_sync: ms=322570 bit_offset=79
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]bt_xtal_sync: ms=322660 bit_offset=81
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]bt_xtal_sync: ms=322766 bit_offset=82
[09:55:57]
[09:55:57]
[09:55:57]Update 26M: diff=12 ms=793 ppb=15118
[09:55:57]
[09:55:57]
[09:55:57]bt_xtal_sync: Bad ppb=15118 last_valid_ppb=16495 last_tune_ppb=0 (1)
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]bt_xtal_sync: ms=322849 bit_offset=83
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]bt_xtal_sync: ms=322928 bit_offset=85
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]cache underflow retry:0
[09:55:57]
[09:55:57]a2dp_audio_more_data decode err 3584/4096
[09:55:57]set to APP_AUDIO_CACHE_CACHEING
[09:55:57]a2dp_audio_more_data cache not ready skip frame 0
[09:55:57]
[09:55:57]bt_xtal_sync: ms=323029 bit_offset=86
[09:55:57]a2dp_audio_more_data cache not ready skip frame 0
[09:55:57]
[09:55:57]cache add:585
[09:55:57]
[09:55:57]a2dp_audio_more_data cache not ready skip frame 0
[09:55:57]
[09:55:57]a2dp_audio_more_data cache not ready skip frame 0
[09:55:57]
[09:55:57]cache add:585
[09:55:57]
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]bt_xtal_sync: ms=323079 bit_offset=87
[09:55:57]a2dp_audio_more_data cache not ready skip frame 0
[09:55:57]
[09:55:57]cache add:585
[09:55:57]
[09:55:57]cache add:585
[09:55:57]
[09:55:57]a2dp_audio_more_data cache not ready skip frame 0
[09:55:57]
[09:55:57]cache add:585
[09:55:57]
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]a2dp_audio_more_data cache not ready skip frame 0
[09:55:57]
[09:55:57]cache add:585
[09:55:57]
[09:55:57]cache add:585
[09:55:57]
[09:55:57]bt_xtal_sync: ms=323168 bit_offset=89
[09:55:57]a2dp_audio_more_data cache not ready skip frame 0
[09:55:57]
[09:55:57]cache add:585
[09:55:57]
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]cache add:585
[09:55:57]
[09:55:57]cache add:585
[09:55:57]
[09:55:57]a2dp_audio_more_data cache not ready skip frame 0
[09:55:57]
[09:55:57]cache add:585
[09:55:57]
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:57]cache add:585
[09:55:57]
[09:55:57]cache ok:7605
[09:55:57]
[09:55:57][TX]
[09:55:57]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58]bt_xtal_sync: ms=323333 bit_offset=91
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58]bt_xtal_sync: ms=323368 bit_offset=92
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58]bt_xtal_sync: ms=323555 bit_offset=95
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58]bt_xtal_sync: ms=323638 bit_offset=96
[09:55:58]
[09:55:58]
[09:55:58]Update 26M: diff=10 ms=608 ppb=16427
[09:55:58]
[09:55:58]
[09:55:58]bt_xtal_sync: Bad ppb=16427 last_valid_ppb=15118 last_tune_ppb=0 (2)
[09:55:58]bt_xtal_sync: ms=323679 bit_offset=97
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58]bt_xtal_sync: ms=323769 bit_offset=98
[09:55:58],rssi_monitor[0]:336 ,rssi_monitor[1]: 149 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:55:58]
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58]bt_xtal_sync: ms=323855 bit_offset=99
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58]bt_xtal_sync: ms=323943 bit_offset=101
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58]bt_xtal_sync: ms=324094 bit_offset=103
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:58]bt_xtal_sync: ms=324213 bit_offset=105
[09:55:58][TX]
[09:55:58]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=324290 bit_offset=106
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=324346 bit_offset=107
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=324409 bit_offset=108
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=324473 bit_offset=109
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=324606 bit_offset=111
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=324691 bit_offset=112
[09:55:59]
[09:55:59]
[09:55:59]Update 26M: diff=12 ms=836 ppb=14350
[09:55:59]
[09:55:59]
[09:55:59]bt_xtal_sync: Bad ppb=14350 last_valid_ppb=16427 last_tune_ppb=0 (3)
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=324729 bit_offset=113
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=324801 bit_offset=114
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=324839 bit_offset=115
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=324929 bit_offset=116
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59]bt_xtal_sync: ms=325110 bit_offset=119
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:55:59][TX]
[09:55:59]01 35 0c 05 01 80 00 03 00
[09:56:00]bt_xtal_sync: ms=325245 bit_offset=121
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00]bt_xtal_sync: ms=325380 bit_offset=123
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00]bt_xtal_sync: ms=325486 bit_offset=125
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00]bt_xtal_sync: ms=325590 bit_offset=127
[09:56:00]
[09:56:00]
[09:56:00]Update 26M: diff=11 ms=751 ppb=14642
[09:56:00]
[09:56:00]
[09:56:00]update_xtal_tune_factor: Offset too small: 0
[09:56:00]
[09:56:00]
[09:56:00]bt_tune_xtal: BAD new_val=118 init_val=45 tune_factor=200
[09:56:00]
[09:56:00]
[09:56:00]tune_ppb=0
[09:56:00]
[09:56:00]
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00]bt_xtal_sync: ms=325675 bit_offset=128
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00]bt_xtal_sync: ms=325835 bit_offset=130
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00]bt_xtal_sync: ms=325898 bit_offset=131
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00]bt_xtal_sync: ms=325970 bit_offset=133
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00],rssi_monitor[0]:656 ,rssi_monitor[1]: 169 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:56:00]
[09:56:00]bt_xtal_sync: ms=326139 bit_offset=135
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:00][TX]
[09:56:00]01 35 0c 05 01 80 00 03 00
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01]bt_xtal_sync: ms=326290 bit_offset=138
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01]bt_xtal_sync: ms=326444 bit_offset=140
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01]bt_xtal_sync: ms=326499 bit_offset=141
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01]bt_xtal_sync: ms=326665 bit_offset=143
[09:56:01]
[09:56:01]
[09:56:01]Update 26M: diff=12 ms=767 ppb=15635
[09:56:01]
[09:56:01]
[09:56:01]update_xtal_tune_factor: Offset too small: 0
[09:56:01]
[09:56:01]
[09:56:01]bt_tune_xtal: BAD new_val=123 init_val=45 tune_factor=200
[09:56:01]
[09:56:01]
[09:56:01]tune_ppb=0
[09:56:01]
[09:56:01]
[09:56:01]bt_xtal_sync: ms=326750 bit_offset=145
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01]bt_xtal_sync: ms=326848 bit_offset=146
[09:56:01]cache underflow retry:0
[09:56:01]
[09:56:01]a2dp_audio_more_data decode err 1536/4096
[09:56:01]set to APP_AUDIO_CACHE_CACHEING
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]bt_xtal_sync: ms=326950 bit_offset=148
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]bt_xtal_sync: ms=327006 bit_offset=149
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01]bt_xtal_sync: ms=327066 bit_offset=150
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01]cache add:585
[09:56:01]
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]bt_xtal_sync: ms=327164 bit_offset=151
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01]a2dp_audio_more_data cache not ready skip frame 0
[09:56:01]
[09:56:01]cache add:585
[09:56:01]
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01]cache ok:7605
[09:56:01]
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:01][TX]
[09:56:01]01 35 0c 05 01 80 00 03 00
[09:56:02]bt_xtal_sync: ms=327294 bit_offset=153
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02]bt_xtal_sync: ms=327365 bit_offset=154
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02]bt_xtal_sync: ms=327389 bit_offset=155
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02]bt_xtal_sync: ms=327546 bit_offset=157
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02]bt_xtal_sync: ms=327683 bit_offset=159
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02]bt_xtal_sync: ms=327758 bit_offset=160
[09:56:02]
[09:56:02]
[09:56:02]Update 26M: diff=12 ms=807 ppb=14861
[09:56:02]
[09:56:02]
[09:56:02]update_xtal_tune_factor: Offset too small: 0
[09:56:02]
[09:56:02]
[09:56:02]bt_tune_xtal: BAD new_val=119 init_val=45 tune_factor=200
[09:56:02]
[09:56:02]
[09:56:02]tune_ppb=0
[09:56:02]
[09:56:02]
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02]bt_xtal_sync: ms=327885 bit_offset=162
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02]bt_xtal_sync: ms=327983 bit_offset=164
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02]bt_xtal_sync: ms=328088 bit_offset=166
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:02][TX]
[09:56:02]01 35 0c 05 01 80 00 03 00
[09:56:03]bt_xtal_sync: ms=328281 bit_offset=169
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03],rssi_monitor[0]:273 ,rssi_monitor[1]: 157 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:56:03]
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03]bt_xtal_sync: ms=328464 bit_offset=171
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03]cache underflow retry:0
[09:56:03]
[09:56:03]a2dp_audio_more_data decode err 3584/4096
[09:56:03]set to APP_AUDIO_CACHE_CACHEING
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]bt_xtal_sync: ms=328591 bit_offset=173
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]cache add:585
[09:56:03]
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]cache add:585
[09:56:03]
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03]cache add:585
[09:56:03]
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]cache add:585
[09:56:03]
[09:56:03]bt_xtal_sync: ms=328670 bit_offset=175
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]cache add:585
[09:56:03]
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03]cache add:585
[09:56:03]
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]cache add:585
[09:56:03]
[09:56:03]cache add:585
[09:56:03]
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]bt_xtal_sync: ms=328783 bit_offset=176
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]cache add:585
[09:56:03]
[09:56:03]cache add:585
[09:56:03]
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]bt_xtal_sync: ms=328835 bit_offset=177
[09:56:03]
[09:56:03]
[09:56:03]Update 26M: diff=12 ms=747 ppb=16054
[09:56:03]
[09:56:03]
[09:56:03]bt_xtal_sync: Bad ppb=16054 last_valid_ppb=14861 last_tune_ppb=0 (1)
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]cache add:585
[09:56:03]
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]cache add:585
[09:56:03]
[09:56:03]bt_xtal_sync: ms=328900 bit_offset=178
[09:56:03]a2dp_audio_more_data cache not ready skip frame 0
[09:56:03]
[09:56:03]cache ok:7605
[09:56:03]
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03]bt_xtal_sync: ms=328973 bit_offset=179
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03]bt_xtal_sync: ms=329019 bit_offset=180
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03][TX]
[09:56:03]01 35 0c 05 01 80 00 03 00
[09:56:03]bt_xtal_sync: ms=329151 bit_offset=182
[09:56:03]bt_xtal_sync: ms=329203 bit_offset=183
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04]bt_xtal_sync: ms=329306 bit_offset=185
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04]bt_xtal_sync: ms=329503 bit_offset=187
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04]bt_xtal_sync: ms=329594 bit_offset=189
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04]bt_xtal_sync: ms=329663 bit_offset=190
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04]CPU USAGE: busy=40 light_sleep=60 deep_sleep=0
[09:56:04]bt_xtal_sync: ms=329738 bit_offset=191
[09:56:04]bt_xtal_sync: ms=329808 bit_offset=192
[09:56:04]cache underflow retry:0
[09:56:04]
[09:56:04]a2dp_audio_more_data decode err 0/4096
[09:56:04]set to APP_AUDIO_CACHE_CACHEING
[09:56:04]bt_xtal_sync: ms=329845 bit_offset=193
[09:56:04]
[09:56:04]
[09:56:04]Update 26M: diff=13 ms=826 ppb=15734
[09:56:04]
[09:56:04]
[09:56:04]update_xtal_tune_factor: Offset too small: 0
[09:56:04]
[09:56:04]
[09:56:04]bt_tune_xtal: BAD new_val=124 init_val=45 tune_factor=200
[09:56:04]
[09:56:04]
[09:56:04]tune_ppb=0
[09:56:04]
[09:56:04]
[09:56:04]a2dp_audio_more_data cache not ready skip frame 0
[09:56:04]
[09:56:04]cache add:585
[09:56:04]
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04]cache add:585
[09:56:04]
[09:56:04]cache add:585
[09:56:04]
[09:56:04]cache add:585
[09:56:04]
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04]cache add:585
[09:56:04]
[09:56:04]a2dp_audio_more_data cache not ready skip frame 0
[09:56:04]
[09:56:04]cache add:585
[09:56:04]
[09:56:04]cache add:585
[09:56:04]
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04]cache add:585
[09:56:04]
[09:56:04]a2dp_audio_more_data cache not ready skip frame 0
[09:56:04]
[09:56:04]cache add:585
[09:56:04]
[09:56:04]bt_xtal_sync: ms=329925 bit_offset=194
[09:56:04]a2dp_audio_more_data cache not ready skip frame 0
[09:56:04]
[09:56:04]cache add:585
[09:56:04]
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04]cache add:585
[09:56:04]
[09:56:04]a2dp_audio_more_data cache not ready skip frame 0
[09:56:04]
[09:56:04]cache add:585
[09:56:04]
[09:56:04]a2dp_audio_more_data cache not ready skip frame 0
[09:56:04]
[09:56:04]cache ok:7605
[09:56:04]
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04]bt_xtal_sync: ms=330111 bit_offset=197
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:04][TX]
[09:56:04]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05]bt_xtal_sync: ms=330268 bit_offset=200
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05]bt_xtal_sync: ms=330415 bit_offset=202
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05]bt_xtal_sync: ms=330494 bit_offset=203
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05]bt_xtal_sync: ms=330650 bit_offset=206
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05]bt_xtal_sync: ms=330719 bit_offset=207
[09:56:05],rssi_monitor[0]:752 ,rssi_monitor[1]: 175 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:56:05]
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05]bt_xtal_sync: ms=330816 bit_offset=208
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05]bt_xtal_sync: ms=330913 bit_offset=210
[09:56:05]
[09:56:05]
[09:56:05]Update 26M: diff=11 ms=645 ppb=17054
[09:56:05]
[09:56:05]
[09:56:05]bt_xtal_sync: Bad ppb=17054 last_valid_ppb=15734 last_tune_ppb=0 (1)
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05]bt_xtal_sync: ms=331019 bit_offset=211
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05]bt_xtal_sync: ms=331089 bit_offset=212
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05][TX]
[09:56:05]01 35 0c 05 01 80 00 03 00
[09:56:05]bt_xtal_sync: ms=331229 bit_offset=215
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]cache underflow retry:0
[09:56:06]
[09:56:06]a2dp_audio_more_data decode err 2048/4096
[09:56:06]set to APP_AUDIO_CACHE_CACHEING
[09:56:06]cache add:585
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]cache add:585
[09:56:06]
[09:56:06]bt_xtal_sync: ms=331325 bit_offset=216
[09:56:06]a2dp_audio_more_data cache not ready skip frame 0
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]a2dp_audio_more_data cache not ready skip frame 0
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06]a2dp_audio_more_data cache not ready skip frame 0
[09:56:06]
[09:56:06]a2dp_audio_more_data cache not ready skip frame 0
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]a2dp_audio_more_data cache not ready skip frame 0
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06]a2dp_audio_more_data cache not ready skip frame 0
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]bt_xtal_sync: ms=331468 bit_offset=218
[09:56:06]a2dp_audio_more_data cache not ready skip frame 0
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06]cache ok:7605
[09:56:06]
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]bt_xtal_sync: ms=331619 bit_offset=221
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]bt_xtal_sync: ms=331745 bit_offset=222
[09:56:06]bt_xtal_sync: ms=331824 bit_offset=224
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]bt_xtal_sync: ms=331970 bit_offset=226
[09:56:06]
[09:56:06]
[09:56:06]Update 26M: diff=12 ms=741 ppb=16189
[09:56:06]
[09:56:06]
[09:56:06]update_xtal_tune_factor: Offset too small: 0
[09:56:06]
[09:56:06]
[09:56:06]bt_tune_xtal: BAD new_val=126 init_val=45 tune_factor=200
[09:56:06]
[09:56:06]
[09:56:06]tune_ppb=0
[09:56:06]
[09:56:06]
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]bt_xtal_sync: ms=332075 bit_offset=228
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]cache underflow retry:0
[09:56:06]
[09:56:06]a2dp_audio_more_data decode err 3072/4096
[09:56:06]set to APP_AUDIO_CACHE_CACHEING
[09:56:06]cache add:585
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06]cache add:585
[09:56:06]
[09:56:06][TX]
[09:56:06]01 35 0c 05 01 80 00 03 00
[09:56:06]a2dp_audio_more_data cache not ready skip frame 0
[09:56:06]
[09:56:07]bt_xtal_sync: ms=332266 bit_offset=231
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07][TX]
[09:56:07]01 35 0c 05 01 80 00 03 00
[09:56:07]cache add:585
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07]bt_xtal_sync: ms=332378 bit_offset=232
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07][TX]
[09:56:07]01 35 0c 05 01 80 00 03 00
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07][TX]
[09:56:07]01 35 0c 05 01 80 00 03 00
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]bt_xtal_sync: ms=332506 bit_offset=234
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]*** SYSFREQ user=3 freq=2
[09:56:07]*** SYSFREQ top_user=3
[09:56:07]app_battery_handle_process 0,4053
[09:56:07]checker: id:0 state:3 mode:0 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:56:07]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:56:07]remDev:2000e404 a2dp State:3 remDev:2000e404 hf_channel Connected:1 remDev:2000e404
[09:56:07]cache add:585
[09:56:07]
[09:56:07]bt_xtal_sync: ms=332591 bit_offset=236
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]cache ok:7605
[09:56:07]
[09:56:07][TX]
[09:56:07]01 35 0c 05 01 80 00 03 00
[09:56:07]bt_xtal_sync: ms=332666 bit_offset=237
[09:56:07][TX]
[09:56:07]01 35 0c 05 01 80 00 03 00
[09:56:07]bt_xtal_sync: ms=332728 bit_offset=238
[09:56:07]bt_xtal_sync: ms=332799 bit_offset=239
[09:56:07][TX]
[09:56:07]01 35 0c 05 01 80 00 02 00
[09:56:07]bt_xtal_sync: ms=332864 bit_offset=240
[09:56:07]cache underflow retry:0
[09:56:07]
[09:56:07]a2dp_audio_more_data decode err 0/4096
[09:56:07]set to APP_AUDIO_CACHE_CACHEING
[09:56:07]cache add:585
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]bt_xtal_sync: ms=332963 bit_offset=241
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07][TX]
[09:56:07]01 35 0c 05 01 80 00 01 00
[09:56:07]bt_xtal_sync: ms=333040 bit_offset=242
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07],rssi_monitor[0]:672 ,rssi_monitor[1]: 170 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07]bt_xtal_sync: ms=333089 bit_offset=243
[09:56:07]
[09:56:07]
[09:56:07]Update 26M: diff=12 ms=711 ppb=16872
[09:56:07]
[09:56:07]
[09:56:07]update_xtal_tune_factor: Offset too small: 0
[09:56:07]
[09:56:07]
[09:56:07]bt_tune_xtal: BAD new_val=129 init_val=45 tune_factor=200
[09:56:07]
[09:56:07]
[09:56:07]tune_ppb=0
[09:56:07]
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]bt_xtal_sync: ms=333123 bit_offset=244
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07][TX]
[09:56:07]01 35 0c 05 01 80 00 03 00
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]cache add:585
[09:56:07]
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:07]bt_xtal_sync: ms=333245 bit_offset=246
[09:56:07]a2dp_audio_more_data cache not ready skip frame 0
[09:56:07]
[09:56:08]cache add:585
[09:56:08]
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08]cache add:585
[09:56:08]
[09:56:08]bt_xtal_sync: ms=333393 bit_offset=248
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache ok:7605
[09:56:08]
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08]bt_xtal_sync: ms=333554 bit_offset=251
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08]bt_xtal_sync: ms=333684 bit_offset=253
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08]bt_xtal_sync: ms=333770 bit_offset=254
[09:56:08]cache underflow retry:0
[09:56:08]
[09:56:08]a2dp_audio_more_data decode err 512/4096
[09:56:08]set to APP_AUDIO_CACHE_CACHEING
[09:56:08]cache add:585
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08]bt_xtal_sync: ms=333878 bit_offset=255
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08][TX]
[09:56:08]bt_xtal_sync: ms=333993 bit_offset=257
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]bt_xtal_sync: ms=334084 bit_offset=259
[09:56:08]
[09:56:08]
[09:56:08]Update 26M: diff=11 ms=691 ppb=15913
[09:56:08]
[09:56:08]
[09:56:08]update_xtal_tune_factor: Offset too small: 0
[09:56:08]
[09:56:08]
[09:56:08]bt_tune_xtal: BAD new_val=125 init_val=45 tune_factor=200
[09:56:08]
[09:56:08]
[09:56:08]tune_ppb=0
[09:56:08]
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache add:585
[09:56:08]
[09:56:08][TX]
[09:56:08]01 35 0c 05 01 80 00 03 00
[09:56:08]cache add:585
[09:56:08]
[09:56:08]bt_xtal_sync: ms=334241 bit_offset=261
[09:56:08]a2dp_audio_more_data cache not ready skip frame 0
[09:56:08]
[09:56:08]cache ok:7605
[09:56:08]
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09]bt_xtal_sync: ms=334298 bit_offset=262
[09:56:09]bt_xtal_sync: ms=334331 bit_offset=263
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09]bt_xtal_sync: ms=334441 bit_offset=264
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09]bt_xtal_sync: ms=334607 bit_offset=267
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09]cache underflow retry:0
[09:56:09]
[09:56:09]a2dp_audio_more_data decode err 1024/4096
[09:56:09]set to APP_AUDIO_CACHE_CACHEING
[09:56:09]cache add:585
[09:56:09]
[09:56:09]cache add:585
[09:56:09]
[09:56:09]bt_xtal_sync: ms=334690 bit_offset=268
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]cache add:585
[09:56:09]
[09:56:09][TX]
[09:56:09]bt_xtal_sync: ms=334728 bit_offset=269
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09]cache add:585
[09:56:09]
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]cache add:585
[09:56:09]
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]cache add:585
[09:56:09]
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]cache add:585
[09:56:09]
[09:56:09]cache add:585
[09:56:09]
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]cache add:585
[09:56:09]
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09]cache add:585
[09:56:09]
[09:56:09]bt_xtal_sync: ms=334910 bit_offset=271
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]cache add:585
[09:56:09]
[09:56:09]cache add:585
[09:56:09]
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]bt_xtal_sync: ms=334971 bit_offset=272
[09:56:09]a2dp_audio_more_data cache not ready skip frame 0
[09:56:09]
[09:56:09]cache ok:7605
[09:56:09]
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09]bt_xtal_sync: ms=335110 bit_offset=274
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:09]bt_xtal_sync: ms=335218 bit_offset=276
[09:56:09][TX]
[09:56:09]01 35 0c 05 01 80 00 03 00
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10]bt_xtal_sync: ms=335274 bit_offset=277
[09:56:10]
[09:56:10]
[09:56:10]Update 26M: diff=13 ms=942 ppb=13793
[09:56:10]
[09:56:10]
[09:56:10]bt_xtal_sync: Bad ppb=13793 last_valid_ppb=15913 last_tune_ppb=0 (1)
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10]bt_xtal_sync: ms=335368 bit_offset=278
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10],rssi_monitor[0]:832 ,rssi_monitor[1]: 180 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:56:10]
[09:56:10]g_sbc_err_cnt: 6
[09:56:10]
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10]bt_xtal_sync: ms=335514 bit_offset=281
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 02 00
[09:56:10]cache underflow retry:0
[09:56:10]
[09:56:10]a2dp_audio_more_data decode err 2560/4096
[09:56:10]set to APP_AUDIO_CACHE_CACHEING
[09:56:10]bt_xtal_sync: ms=335578 bit_offset=282
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]cache add:585
[09:56:10]
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]cache add:585
[09:56:10]
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10]cache add:585
[09:56:10]
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]bt_xtal_sync: ms=335669 bit_offset=283
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]cache add:585
[09:56:10]
[09:56:10]cache add:585
[09:56:10]
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]cache add:585
[09:56:10]
[09:56:10]bt_xtal_sync: ms=335729 bit_offset=284
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]cache add:585
[09:56:10]
[09:56:10]cache add:585
[09:56:10]
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10]cache add:585
[09:56:10]
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]bt_xtal_sync: ms=335825 bit_offset=286
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 01 00
[09:56:10]cache add:585
[09:56:10]
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]a2dp_audio_more_data cache not ready skip frame 0
[09:56:10]
[09:56:10]cache add:585
[09:56:10]
[09:56:10]cache add:585
[09:56:10]
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10]cache ok:7605
[09:56:10]
[09:56:10]bt_xtal_sync: ms=335938 bit_offset=287
[09:56:10]bt_xtal_sync: ms=335985 bit_offset=288
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10]bt_xtal_sync: ms=336129 bit_offset=290
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10][TX]
[09:56:10]01 35 0c 05 01 80 00 03 00
[09:56:10]bt_xtal_sync: ms=336255 bit_offset=292
[09:56:10][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11]bt_xtal_sync: ms=336386 bit_offset=294
[09:56:11]bt_xtal_sync: ms=336448 bit_offset=295
[09:56:11]
[09:56:11]
[09:56:11]Update 26M: diff=13 ms=870 ppb=14943
[09:56:11]
[09:56:11]
[09:56:11]bt_xtal_sync: Bad ppb=14943 last_valid_ppb=13793 last_tune_ppb=0 (2)
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 02 00
[09:56:11]bt_xtal_sync: ms=336489 bit_offset=296
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11]bt_xtal_sync: ms=336575 bit_offset=297
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11]cache underflow retry:0
[09:56:11]
[09:56:11]a2dp_audio_more_data decode err 2560/4096
[09:56:11]set to APP_AUDIO_CACHE_CACHEING
[09:56:11]bt_xtal_sync: ms=336750 bit_offset=300
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]bt_xtal_sync: ms=336836 bit_offset=301
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11]bt_xtal_sync: ms=336870 bit_offset=302
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11]bt_xtal_sync: ms=336923 bit_offset=303
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11]bt_xtal_sync: ms=337001 bit_offset=304
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]cache add:585
[09:56:11]
[09:56:11]a2dp_audio_more_data cache not ready skip frame 0
[09:56:11]
[09:56:11]cache ok:7605
[09:56:11]
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11]bt_xtal_sync: ms=337128 bit_offset=306
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:11][TX]
[09:56:11]01 35 0c 05 01 80 00 03 00
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12]bt_xtal_sync: ms=337358 bit_offset=309
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12]bt_xtal_sync: ms=337513 bit_offset=312
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12]bt_xtal_sync: ms=337666 bit_offset=314
[09:56:12]cache underflow retry:0
[09:56:12]
[09:56:12]a2dp_audio_more_data decode err 3584/4096
[09:56:12]set to APP_AUDIO_CACHE_CACHEING
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12]bt_xtal_sync: ms=337753 bit_offset=315
[09:56:12]
[09:56:12]
[09:56:12]Update 26M: diff=16 ms=1002 ppb=15960
[09:56:12]
[09:56:12]
[09:56:12]bt_xtal_sync: Bad ppb=15960 last_valid_ppb=14943 last_tune_ppb=0 (3)
[09:56:12],rssi_monitor[0]:368 ,rssi_monitor[1]: 151 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:56:12]
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12]cache add:585
[09:56:12]
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]bt_xtal_sync: ms=337846 bit_offset=317
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12][TX]
[09:56:12]bt_xtal_sync: ms=337964 bit_offset=318
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12]cache add:585
[09:56:12]
[09:56:12]bt_xtal_sync: ms=338020 bit_offset=319
[09:56:12]a2dp_audio_more_data cache not ready skip frame 0
[09:56:12]
[09:56:12]cache ok:7605
[09:56:12]
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:12]bt_xtal_sync: ms=338153 bit_offset=322
[09:56:12][TX]
[09:56:12]01 35 0c 05 01 80 00 03 00
[09:56:13]bt_xtal_sync: ms=338264 bit_offset=323
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13]bt_xtal_sync: ms=338424 bit_offset=326
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13]bt_xtal_sync: ms=338570 bit_offset=328
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13]bt_xtal_sync: ms=338706 bit_offset=330
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13]bt_xtal_sync: ms=338763 bit_offset=331
[09:56:13]
[09:56:13]
[09:56:13]Update 26M: diff=11 ms=742 ppb=14815
[09:56:13]
[09:56:13]
[09:56:13]bt_xtal_sync: Bad ppb=14815 last_valid_ppb=15960 last_tune_ppb=0 (4)
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13]bt_xtal_sync: ms=338875 bit_offset=332
[09:56:13]cache underflow retry:0
[09:56:13]
[09:56:13]a2dp_audio_more_data decode err 2048/4096
[09:56:13]set to APP_AUDIO_CACHE_CACHEING
[09:56:13]cache add:585
[09:56:13]
[09:56:13]cache add:585
[09:56:13]
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13]cache add:585
[09:56:13]
[09:56:13]cache add:585
[09:56:13]
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13]cache add:585
[09:56:13]
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13]cache add:585
[09:56:13]
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13]cache add:585
[09:56:13]
[09:56:13]bt_xtal_sync: ms=338988 bit_offset=334
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13]cache add:585
[09:56:13]
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13]cache add:585
[09:56:13]
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13]cache add:585
[09:56:13]
[09:56:13]cache add:585
[09:56:13]
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13]cache add:585
[09:56:13]
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13]bt_xtal_sync: ms=339123 bit_offset=336
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13]a2dp_audio_more_data cache not ready skip frame 0
[09:56:13]
[09:56:13]cache ok:7605
[09:56:13]
[09:56:13]bt_xtal_sync: ms=339179 bit_offset=337
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:13][TX]
[09:56:13]01 35 0c 05 01 80 00 03 00
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14]bt_xtal_sync: ms=339318 bit_offset=339
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14]bt_xtal_sync: ms=339484 bit_offset=342
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14]bt_xtal_sync: ms=339567 bit_offset=343
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14]CPU USAGE: busy=52 light_sleep=48 deep_sleep=0
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14]bt_xtal_sync: ms=339724 bit_offset=346
[09:56:14]cache underflow retry:0
[09:56:14]
[09:56:14]a2dp_audio_more_data decode err 2048/4096
[09:56:14]set to APP_AUDIO_CACHE_CACHEING
[09:56:14]cache add:585
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]cache add:585
[09:56:14]
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]bt_xtal_sync: ms=339860 bit_offset=348
[09:56:14]
[09:56:14]
[09:56:14]Update 26M: diff=11 ms=737 ppb=14915
[09:56:14]
[09:56:14]
[09:56:14]update_xtal_tune_factor: Offset too small: 0
[09:56:14]
[09:56:14]
[09:56:14]bt_tune_xtal: BAD new_val=120 init_val=45 tune_factor=200
[09:56:14]
[09:56:14]
[09:56:14]tune_ppb=0
[09:56:14]
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]bt_xtal_sync: ms=339934 bit_offset=349
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]cache add:585
[09:56:14]
[09:56:14]cache add:585
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]cache add:585
[09:56:14]
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14],rssi_monitor[0]:577 ,rssi_monitor[1]: 176 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]cache add:585
[09:56:14]
[09:56:14]cache add:585
[09:56:14]
[09:56:14]cache add:585
[09:56:14]
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14]bt_xtal_sync: ms=340086 bit_offset=351
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]cache add:585
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]cache add:585
[09:56:14]
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14]cache add:585
[09:56:14]
[09:56:14]bt_xtal_sync: ms=340158 bit_offset=352
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]a2dp_audio_more_data cache not ready skip frame 0
[09:56:14]
[09:56:14]cache add:585
[09:56:14]
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:14]cache ok:7605
[09:56:14]
[09:56:14][TX]
[09:56:14]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15]bt_xtal_sync: ms=340311 bit_offset=355
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15]bt_xtal_sync: ms=340505 bit_offset=358
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15]bt_xtal_sync: ms=340583 bit_offset=359
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15]bt_xtal_sync: ms=340684 bit_offset=360
[09:56:15]bt_xtal_sync: ms=340725 bit_offset=361
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15]bt_xtal_sync: ms=340815 bit_offset=362
[09:56:15]
[09:56:15]
[09:56:15]Update 26M: diff=11 ms=657 ppb=16730
[09:56:15]
[09:56:15]
[09:56:15]bt_xtal_sync: Bad ppb=16730 last_valid_ppb=14915 last_tune_ppb=0 (1)
[09:56:15]bt_xtal_sync: ms=340906 bit_offset=364
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15]bt_xtal_sync: ms=340990 bit_offset=365
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15]cache underflow retry:0
[09:56:15]
[09:56:15]a2dp_audio_more_data decode err 1024/4096
[09:56:15]set to APP_AUDIO_CACHE_CACHEING
[09:56:15]cache add:585
[09:56:15]
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15]cache add:585
[09:56:15]
[09:56:15]cache add:585
[09:56:15]
[09:56:15]bt_xtal_sync: ms=341125 bit_offset=367
[09:56:15]a2dp_audio_more_data cache not ready skip frame 0
[09:56:15]
[09:56:15]cache add:585
[09:56:15]
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15]cache add:585
[09:56:15]
[09:56:15]a2dp_audio_more_data cache not ready skip frame 0
[09:56:15]
[09:56:15]cache add:585
[09:56:15]
[09:56:15]a2dp_audio_more_data cache not ready skip frame 0
[09:56:15]
[09:56:15]cache add:585
[09:56:15]
[09:56:15][TX]
[09:56:15]01 35 0c 05 01 80 00 03 00
[09:56:15]bt_xtal_sync: ms=341203 bit_offset=368
[09:56:15]a2dp_audio_more_data cache not ready skip frame 0
[09:56:15]
[09:56:15]cache add:585
[09:56:15]
[09:56:15]a2dp_audio_more_data cache not ready skip frame 0
[09:56:15]
[09:56:15]a2dp_audio_more_data cache not ready skip frame 0
[09:56:15]
[09:56:16]a2dp_audio_more_data cache not ready skip frame 0
[09:56:16]
[09:56:16]bt_xtal_sync: ms=341293 bit_offset=369
[09:56:16]a2dp_audio_more_data cache not ready skip frame 0
[09:56:16]
[09:56:16]cache add:585
[09:56:16]
[09:56:16]a2dp_audio_more_data cache not ready skip frame 0
[09:56:16]
[09:56:16]cache add:585
[09:56:16]
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16]cache add:585
[09:56:16]
[09:56:16]a2dp_audio_more_data cache not ready skip frame 0
[09:56:16]
[09:56:16]cache add:585
[09:56:16]
[09:56:16]cache ok:7605
[09:56:16]
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16]bt_xtal_sync: ms=341413 bit_offset=371
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16]bt_xtal_sync: ms=341591 bit_offset=374
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16]bt_xtal_sync: ms=341666 bit_offset=375
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16]bt_xtal_sync: ms=341753 bit_offset=377
[09:56:16]
[09:56:16]
[09:56:16]Update 26M: diff=10 ms=627 ppb=15936
[09:56:16]
[09:56:16]
[09:56:16]update_xtal_tune_factor: Offset too small: 0
[09:56:16]
[09:56:16]
[09:56:16]bt_tune_xtal: BAD new_val=125 init_val=45 tune_factor=200
[09:56:16]
[09:56:16]
[09:56:16]tune_ppb=0
[09:56:16]
[09:56:16]
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16]bt_xtal_sync: ms=341828 bit_offset=378
[09:56:16]bt_xtal_sync: ms=341901 bit_offset=379
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16]bt_xtal_sync: ms=341989 bit_offset=380
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16]bt_xtal_sync: ms=342108 bit_offset=382
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 03 00
[09:56:16]bt_xtal_sync: ms=342199 bit_offset=383
[09:56:16][TX]
[09:56:16]01 35 0c 05 01 80 00 02 00
[09:56:17][TX]
[09:56:17]01 35 0c 05 01 80 00 03 00
[09:56:17][TX]
[09:56:17]01 35 0c 05 01 80 00 03 00
[09:56:17][TX]
[09:56:17]01 35 0c 05 01 80 00 03 00
[09:56:17]bt_xtal_sync: ms=342343 bit_offset=386
[09:56:17],rssi_monitor[0]:641 ,rssi_monitor[1]: 180 ,rssi_monitor[2]:0,rssi_monitor[3]:128
[09:56:17]
[09:56:17][TX]
[09:56:17]01 35 0c 05 01 80 00 03 00
[09:56:17]bt_xtal_sync: ms=342428 bit_offset=387
[09:56:17]cache underflow retry:0
[09:56:17]
[09:56:17]a2dp_audio_more_data decode err 1536/4096
[09:56:17]set to APP_AUDIO_CACHE_CACHEING
[09:56:17]bt_xtal_sync: ms=342510 bit_offset=388
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17][TX]
[09:56:17]01 35 0c 05 01 80 00 03 00
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17][TX]
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]01 35 0c 05 01 80 00 03 00
[09:56:17]bt_xtal_sync: ms=342670 bit_offset=391
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17][TX]
[09:56:17]01 35 0c 05 01 80 00 03 00
[09:56:17]cache add:585
[09:56:17]
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17]bt_xtal_sync: ms=342736 bit_offset=392
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17][TX]
[09:56:17]01 35 0c 05 01 80 00 03 00
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]cache add:585
[09:56:17]
[09:56:17]bt_xtal_sync: ms=342803 bit_offset=393
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]*** SYSFREQ user=3 freq=2
[09:56:17]*** SYSFREQ top_user=3
[09:56:17]app_battery_handle_process 0,4050
[09:56:17]checker: id:0 state:3 mode:0 role:1 cmghdl:2000d71c sniffInterva:800/800 IsAudioUp:0
[09:56:17]0x0c 0xcf 0x87 0x03 0x42 0xfc
[09:56:17]remDev:2000e404 a2dp State:3 remDev:2000e404 hf_channel Connected:1 remDev:2000e404
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]a2dp_audio_more_data cache not ready skip frame 0
[09:56:17]
[09:56:17]cache ok:7605
[09:56:17]
[09:56:17][TX]
[09:56:17]01 35 0c 05 01 80 00 03 00
[09:56:17]bt_xtal_sync: ms=342920 bit_offset=394
[09:56:17]
[09:56:17]
[09:56:17]Update 26M: diff=14 ms=931 ppb=15034
[09:56:17]
[09:56:17]
[09:56:17]update_xtal_tune_factor: Offset too small: 0
[09:56:17]
[09:56:17]
[09:56:17]bt_tune_xtal: BAD new_val=120 init_val=45 tune_factor=200
[09:56:17]
[09:56:17]
[09:56:17]tune_ppb=0
[09:56:17]
[09:56:17]
[09:56:17][TX]
[09:56:17]01 35 0c 05 01 80 00 03 00
[09:56:17][TX]
[09:56:17]01 35 0c 05 01 80 00 03 00