os-issue-tracker
os-issue-tracker copied to clipboard
Published
20 hours ago •
GrapheneOS
GrapheneOS
Bluetooth disconnecting after 3-10 minutes specifically with Audeze Maxwell
Hi,
I recently got a Pixel 8 pro with GOS and this headset disconnects after a bit for no apparent reason. It works perfectly with other android, linux and windows devices over bluetooth and the phone works perfectly with other BT headphones (I tested Sony WH-1000XM4 and Sony MDR-1000X).
Here's the logcat of the bluetooth system app when reproducing the issue. No other BT devices were conneted this time, but that appears to not matter.
The log starts with connecting the device at 9:43 and it disconnected at 9:47:51
type: logcat
osVersion: google/husky/husky:14/AP2A.240905.003/2024090400:user/release-keys
packageName: com.android.bluetooth:34
buffers: main,system,crash,events,kernel
level: verbose
--------- beginning of events
09-12 09:43:03.784 11791 11791 I auditd : avc=type=1400 audit(0.0:4004): avc: denied { read } for comm="droid.bluetooth" name="u:object_r:odsign_prop:s0" dev="tmpfs" ino=285 scontext=u:r:bluetooth:s0 tcontext=u:object_r:odsign_prop:s0 tclass=file permissive=0
09-12 09:43:03.788 11791 11791 I auditd : avc=type=1400 audit(0.0:4005): avc: denied { getattr } for comm="droid.bluetooth" path="/apex/apex-info-list.xml" dev="tmpfs" ino=83 scontext=u:r:bluetooth:s0 tcontext=u:object_r:apex_info_file:s0 tclass=file permissive=0
09-12 09:43:03.812 11791 11791 I auditd : avc=type=1400 audit(0.0:4006): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot.art" dev="dm-12" ino=1336 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.816 11791 11791 I auditd : avc=type=1400 audit(0.0:4007): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-core-libart.art" dev="dm-12" ino=1270 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.816 11791 11791 I auditd : avc=type=1400 audit(0.0:4008): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-okhttp.art" dev="dm-12" ino=1318 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.816 11791 11791 I auditd : avc=type=1400 audit(0.0:4009): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-bouncycastle.art" dev="dm-12" ino=1258 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.816 11791 11791 I auditd : avc=type=1400 audit(0.0:4010): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-apache-xml.art" dev="dm-12" ino=1252 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.816 11791 11791 I auditd : avc=type=1400 audit(0.0:4011): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-framework.art" dev="dm-12" ino=1306 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.828 11791 11791 I auditd : avc=type=1400 audit(0.0:4012): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-framework-graphics.art" dev="dm-12" ino=1288 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.828 11791 11791 I auditd : avc=type=1400 audit(0.0:4013): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-framework-location.art" dev="dm-12" ino=1294 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.828 11791 11791 I auditd : avc=type=1400 audit(0.0:4014): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-ext.art" dev="dm-12" ino=1276 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.828 11791 11791 I auditd : avc=type=1400 audit(0.0:4015): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-telephony-common.art" dev="dm-12" ino=1324 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.828 11791 11791 I auditd : avc=type=1400 audit(0.0:4016): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-voip-common.art" dev="dm-12" ino=1330 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.828 11791 11791 I auditd : avc=type=1400 audit(0.0:4017): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-ims-common.art" dev="dm-12" ino=1312 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.828 11791 11791 I auditd : avc=type=1400 audit(0.0:4018): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-framework-nfc.art" dev="dm-12" ino=1300 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.828 11791 11791 I auditd : avc=type=1400 audit(0.0:4019): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-core-icu4j.art" dev="dm-12" ino=1264 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.840 11791 11791 I auditd : avc=type=1400 audit(0.0:4020): avc: denied { lock } for comm="droid.bluetooth" path="/system/framework/arm64/boot-framework-adservices.art" dev="dm-12" ino=1282 scontext=u:r:bluetooth:s0 tcontext=u:object_r:system_file:s0 tclass=file permissive=0
09-12 09:43:03.924 11791 11791 I auditd : avc=type=1400 audit(0.0:4021): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=111 scontext=u:r:bluetooth:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
09-12 09:43:03.924 11791 11791 I auditd : avc=type=1400 audit(0.0:4022): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=111 scontext=u:r:bluetooth:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
09-12 09:43:03.924 11791 11791 I auditd : avc=type=1400 audit(0.0:4023): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=111 scontext=u:r:bluetooth:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
09-12 09:43:03.924 11791 11791 I auditd : avc=type=1400 audit(0.0:4024): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=111 scontext=u:r:bluetooth:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
09-12 09:43:03.924 11791 11791 I auditd : avc=type=1400 audit(0.0:4025): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=111 scontext=u:r:bluetooth:s0 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0
09-12 09:43:03.964 11791 11791 I auditd : avc=type=1400 audit(0.0:4026): avc: denied { read } for comm="binder:11791_2" name="u:object_r:qemu_sf_lcd_density_prop:s0" dev="tmpfs" ino=308 scontext=u:r:bluetooth:s0 tcontext=u:object_r:qemu_sf_lcd_density_prop:s0 tclass=file permissive=0
09-12 09:43:03.968 11791 11791 I auditd : avc=type=1400 audit(0.0:4027): avc: denied { read } for comm="binder:11791_2" name="u:object_r:qemu_sf_lcd_density_prop:s0" dev="tmpfs" ino=308 scontext=u:r:bluetooth:s0 tcontext=u:object_r:qemu_sf_lcd_density_prop:s0 tclass=file permissive=0
--------- beginning of system
09-12 09:43:04.032 11791 11791 E ActivityThread: Failed to find provider info for bluetooth_legacy.provider
--------- beginning of main
09-12 09:44:39.749 11791 11866 W bt_stack: [WARNING:client_interface_aidl.cc(571)] ReadAudioData: 512/1024 no data 10 ms
09-12 09:44:39.749 11791 11866 W bt_btif_a2dp_source: btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 512 BYTES OUT OF 1024
09-12 09:44:44.558 11791 11791 I bluetooth: SendMediaUpdate: track_changed=false : play_state=true : queue=false
09-12 09:44:44.558 11791 11835 W avrcp : HandlePlayPosUpdate: Device is not registered for play position updates
09-12 09:44:45.062 11791 11791 I bluetooth: SendMediaUpdate: track_changed=false : play_state=true : queue=false
09-12 09:44:45.063 11791 11835 W avrcp : HandlePlayPosUpdate: Device is not registered for play position updates
09-12 09:44:45.948 11791 11866 W bt_stack: [WARNING:client_interface_aidl.cc(571)] ReadAudioData: 1024/1024 no data 10 ms
09-12 09:44:45.949 11791 11866 W bt_btif_a2dp_source: btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 1024
09-12 09:44:45.949 11791 11866 W a2dp_vendor_ldac_encoder: a2dp_ldac_encode_frames: underflow 6
09-12 09:44:53.848 11791 11866 W bt_stack: [WARNING:client_interface_aidl.cc(571)] ReadAudioData: 1024/1024 no data 10 ms
09-12 09:44:53.849 11791 11866 W bt_btif_a2dp_source: btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 1024
09-12 09:44:53.849 11791 11866 W a2dp_vendor_ldac_encoder: a2dp_ldac_encode_frames: underflow 6
09-12 09:45:03.262 11791 11791 I bluetooth: SendMediaUpdate: track_changed=false : play_state=true : queue=false
09-12 09:45:03.263 11791 11835 W avrcp : HandlePlayPosUpdate: Device is not registered for play position updates
09-12 09:45:03.334 11791 11791 I bluetooth: SendMediaUpdate: track_changed=false : play_state=false : queue=false
09-12 09:45:06.450 11791 11866 W bt_stack: [WARNING:client_interface_aidl.cc(571)] ReadAudioData: 1024/1024 no data 10 ms
09-12 09:45:06.450 11791 11866 W bt_btif_a2dp_source: btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 1024
09-12 09:45:06.450 11791 11866 W a2dp_vendor_ldac_encoder: a2dp_ldac_encode_frames: underflow 7
09-12 09:45:20.989 11791 11866 W bt_stack: [WARNING:client_interface_aidl.cc(571)] ReadAudioData: 1024/1024 no data 10 ms
09-12 09:45:20.989 11791 11866 W bt_btif_a2dp_source: btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 1024
09-12 09:45:20.989 11791 11866 W a2dp_vendor_ldac_encoder: a2dp_ldac_encode_frames: underflow 6
09-12 09:45:56.688 11791 11866 W bt_stack: [WARNING:client_interface_aidl.cc(571)] ReadAudioData: 1024/1024 no data 10 ms
09-12 09:45:56.689 11791 11866 W bt_btif_a2dp_source: btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 1024
09-12 09:45:56.689 11791 11866 W a2dp_vendor_ldac_encoder: a2dp_ldac_encode_frames: underflow 6
09-12 09:46:54.850 11791 11866 W bt_stack: [WARNING:client_interface_aidl.cc(571)] ReadAudioData: 1024/1024 no data 10 ms
09-12 09:46:54.851 11791 11866 W bt_btif_a2dp_source: btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 1024
09-12 09:46:54.851 11791 11866 W a2dp_vendor_ldac_encoder: a2dp_ldac_encode_frames: underflow 7
09-12 09:47:21.212 838 1570 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xff Opcode 0 Length 9 unhandled by debug_VendorEvent
09-12 09:47:21.215 11791 11840 W bluetooth: packages/modules/Bluetooth/system/gd/hci/vendor_specific_event_manager.cc:86 - on_vendor_specific_event: Unhandled vendor specific event of type 0x86
09-12 09:47:22.622 11791 11791 E AudioMediaPlayerWrapper: The callback queue isn't the current queue
09-12 09:47:22.623 11791 11791 W AudioMediaPlayerWrapper: onQueueChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.624 11791 11791 E AudioMediaPlayerWrapper: The callback playback state doesn't match the current state
09-12 09:47:22.631 11791 11791 I bluetooth: SendMediaUpdate: track_changed=true : play_state=true : queue=true
09-12 09:47:22.631 11791 11835 W avrcp : HandleNowPlayingUpdate: Device is not registered for now playing updates
09-12 09:47:22.631 11791 11835 W avrcp : HandlePlayPosUpdate: Device is not registered for play position updates
09-12 09:47:22.631 11791 11835 W avrcp : HandleTrackUpdate: Device is not registered for track changed updates
09-12 09:47:22.634 11791 11791 W AudioMediaPlayerWrapper: onQueueChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.635 11791 11791 W AudioMediaPlayerWrapper: onPlaybackStateChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.637 11791 11791 W AudioMediaPlayerWrapper: onMetadataChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.693 11791 11791 W AudioMediaPlayerWrapper: onQueueChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.694 11791 11791 W AudioMediaPlayerWrapper: onPlaybackStateChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.743 11791 11791 W AudioMediaPlayerWrapper: onQueueChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.744 11791 11791 W AudioMediaPlayerWrapper: onPlaybackStateChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.795 11791 11791 W AudioMediaPlayerWrapper: onQueueChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.795 11791 11791 W AudioMediaPlayerWrapper: onPlaybackStateChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.859 11791 11791 W AudioMediaPlayerWrapper: onQueueChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.862 11791 11791 W AudioMediaPlayerWrapper: onPlaybackStateChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.976 11791 11791 W AudioMediaPlayerWrapper: onQueueChanged(): com.spotify.music tried to update with no new data
09-12 09:47:22.977 11791 11791 W AudioMediaPlayerWrapper: onPlaybackStateChanged(): com.spotify.music tried to update with no new data
09-12 09:47:32.900 11791 11791 W AudioMediaPlayerWrapper: onQueueChanged(): com.spotify.music tried to update with no new data
09-12 09:47:32.907 11791 11791 I bluetooth: SendMediaUpdate: track_changed=false : play_state=true : queue=false
09-12 09:47:32.907 11791 11835 W avrcp : HandlePlayPosUpdate: Device is not registered for play position updates
09-12 09:47:43.489 11791 11866 W bt_stack: [WARNING:client_interface_aidl.cc(571)] ReadAudioData: 1024/1024 no data 10 ms
09-12 09:47:43.490 11791 11866 W bt_btif_a2dp_source: btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 1024
09-12 09:47:43.490 11791 11866 W a2dp_vendor_ldac_encoder: a2dp_ldac_encode_frames: underflow 6
09-12 09:47:51.270 838 1570 W bthal.bqr: ParseLinkQualityRelatedEvt: Generic Parameters: Appro LSTO , Handle: 0x000b, 2DH5, Central, PwLv: 8, RSSI: -56, SNR: 0, UnusedCh: 14, UnidealCh: 0, ReTx: 3279, NoRX: 2217, NAK: 1602, FlowOff: 0, OverFlow: 0, UndFlow: 0, TxTotal: 0, TxUnAcked: 0, TxFlushed: 0, TxLastSubEvent: 0, CRCError: 0, RxDuplicate: 0
09-12 09:47:51.270 838 1570 W bthal.bqr: ParseLinkQualityRelatedEvt: Vendor Parameters: Appro LSTO , Handle: 0x000b, RSSI_C0: -56, RSSI_C1: -57, TxPw_C0: -128, ReTxPw_C0: 8, TxPw_C1: 8, ReTxPw_C1: -128, BFTx: 0x01, BFReTx: 0x01, DivTx: 0x03, DivReTx: 0x02, Overall_link_quality: 240, Tx_link_quality: 240, Rx_link_quality: 240
09-12 09:47:51.270 838 1570 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xff Opcode 0 Length 242 unhandled by debug_VendorEvent
09-12 09:47:51.272 11791 11835 I devctl : btm_vendor_specific_evt: Unhandled BQR subevent 0x58x
09-12 09:47:51.273 11791 11835 I bt_shim_hci: packages/modules/Bluetooth/system/main/shim/hci_layer.cc:299 - operator(): ISO disconnection from GD, handle: 0x0b, reason: 0x22
09-12 09:47:51.274 11791 11835 W l2c_link: l2c_link_check_send_pkts: Can't send, link state: 5 not LST_CONNECTED or power mode BTM_PM_STS_PENDING
09-12 09:47:51.274 11791 11835 W l2c_link: l2c_link_check_send_pkts: Can't send, link state: 5 not LST_CONNECTED or power mode BTM_PM_STS_PENDING
09-12 09:47:51.274 11791 11835 W l2c_link: l2c_link_check_send_pkts: Can't send, link state: 5 not LST_CONNECTED or power mode BTM_PM_STS_PENDING
09-12 09:47:51.275 11791 11835 W l2c_link: l2c_link_check_send_pkts: Can't send, link state: 5 not LST_CONNECTED or power mode BTM_PM_STS_PENDING
09-12 09:47:51.275 11791 11835 W l2c_link: l2c_link_check_send_pkts: Can't send, link state: 5 not LST_CONNECTED or power mode BTM_PM_STS_PENDING
09-12 09:47:51.275 11791 11835 W l2c_link: l2c_link_check_send_pkts: Can't send, link state: 5 not LST_CONNECTED or power mode BTM_PM_STS_PENDING
09-12 09:47:51.275 11791 11835 I bt_bta_av: bta_av_conn_cback: conn_cback bd_addr: xx:xx:xx:xx:16:d6, scb_index: 0
09-12 09:47:51.276 11791 11835 I avrcp : AcceptorControlCb: handle=0000 result=000000 addr=xx:xx:xx:xx:16:d6
09-12 09:47:51.276 11791 11835 I avrcp : AcceptorControlCb: Connection Closed Event
09-12 09:47:51.276 11791 11835 I avrcp : DeviceDisconnected: xx:xx:xx:xx:16:d6 : Device was disconnected
09-12 09:47:51.277 11791 11835 I smp : smp_br_connect_callback: BDA:xx:xx:xx:xx:16:d6 pairing_bda:00:00:00:00:00:00, connected:false
09-12 09:47:51.277 11791 11835 W bt_btm_sec: btm_sec_disconnected: Got uncommon disconnection reason:HCI_ERR_LMP_RESPONSE_TIMEOUT handle:0x000b comment:stack::acl::btm_acl::btm_acl_disconnected
09-12 09:47:51.277 11791 11848 I AvrcpTargetService: deviceDisconnected: device=XX:XX:XX:XX:16:D6
09-12 09:47:51.277 11791 11835 I bt_bta_av: bta_av_better_stream_state_machine: peer xx:xx:xx:xx:16:d6 p_scb=0x41(0x700cfd1a9ce5200) AV event=0x121e(STR_CLOSE) state=3(OPEN) -> 0(INIT)
09-12 09:47:51.277 11791 11835 W bt_bta_av: bta_av_str_closed: peer xx:xx:xx:xx:16:d6 bta_handle:0x41 open_status:0 chnl:64 co_started:1
09-12 09:47:51.277 11791 11835 W btm_acl : btm_toggle_policy_on_for: Unable to find active acl
09-12 09:47:51.277 11791 11835 I bluetooth: packages/modules/Bluetooth/system/gd/hal/snoop_logger.cc:1429 - GetBtSnoopMode: btsnoop_mode_empty: 0
09-12 09:47:51.277 11791 11835 I bt_bta_av: bta_av_str_stopped: peer xx:xx:xx:xx:16:d6 bta_handle:0x41 audio_open_cnt:1, p_data 0x0 start:1
09-12 09:47:51.278 11791 11835 I bluetooth: bta_dm_pm_ssr: Found connected service:BTA_ID_AV app_id:0 peer:xx:xx:xx:xx:16:d6 spec_name:sniff_capable
09-12 09:47:51.278 11791 11835 I bluetooth: bta_dm_pm_ssr: Found connected service:BTA_ID_AV app_id:1 peer:xx:xx:xx:xx:16:d6 spec_name:sniff_capable
09-12 09:47:51.278 11791 11835 W bt_btm_pm: BTM_SetSsrParams: Unable to find power manager for peer:xx:xx:xx:xx:16:d6
09-12 09:47:51.278 11791 11835 W btm_acl : btm_toggle_policy_on_for: Unable to find active acl
09-12 09:47:51.278 11791 11835 W l2c_utils: l2cu_set_acl_priority: L2CAP - no LCB for L2CA_SetAclPriority
09-12 09:47:51.278 11791 11835 I a2dp_codec: init:
09-12 09:47:51.278 11791 11835 I a2dp_codec: createCodec: SBC
09-12 09:47:51.279 11791 11835 I a2dp_codec: init: updated SBC codec priority to 1001
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: AAC
09-12 09:47:51.279 11791 11835 I a2dp_codec: init: updated AAC codec priority to 2001
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: aptX
09-12 09:47:51.279 11791 11835 I a2dp_codec: init: updated aptX codec priority to 3001
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: aptX-HD
09-12 09:47:51.279 11791 11835 I a2dp_codec: init: updated aptX-HD codec priority to 4001
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: LDAC
09-12 09:47:51.279 11791 11835 I a2dp_codec: init: updated LDAC codec priority to 5001
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: LC3 not implemented
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: Opus
09-12 09:47:51.279 11791 11835 I a2dp_codec: init: updated Opus codec priority to 7001
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: UNKNOWN CODEC INDEX
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: UNKNOWN CODEC INDEX
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: UNKNOWN CODEC INDEX
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: UNKNOWN CODEC INDEX
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: SBC SINK
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: AAC SINK
09-12 09:47:51.279 11791 11835 I a2dp_codec: createCodec: LDAC SINK
09-12 09:47:51.288 11791 11835 I a2dp_vendor_ldac_decoder: A2DP_VendorLoadDecoderLdac: cannot open LDAC decoder library libldacBT_bco.so: dlopen failed: library "libldacBT_bco.so" not found
09-12 09:47:51.288 11791 11835 E a2dp_vendor_ldac: init: cannot load the decoder
09-12 09:47:51.288 11791 11835 I a2dp_codec: createCodec: Opus SINK
09-12 09:47:51.288 11791 11835 I a2dp_codec: createCodec: UNKNOWN CODEC INDEX
09-12 09:47:51.288 11791 11835 I a2dp_codec: createCodec: UNKNOWN CODEC INDEX
09-12 09:47:51.288 11791 11835 I a2dp_codec: createCodec: UNKNOWN CODEC INDEX
09-12 09:47:51.288 11791 11835 I a2dp_codec: createCodec: UNKNOWN CODEC INDEX
09-12 09:47:51.288 11791 11835 I a2dp_codec: init: initialized Source codec Opus, idx 6
09-12 09:47:51.288 11791 11835 I a2dp_codec: init: initialized Source codec LDAC, idx 4
09-12 09:47:51.288 11791 11835 I a2dp_codec: init: initialized Source codec aptX-HD, idx 3
09-12 09:47:51.288 11791 11835 I a2dp_codec: init: initialized Source codec aptX, idx 2
09-12 09:47:51.288 11791 11835 I a2dp_codec: init: initialized Source codec AAC, idx 1
09-12 09:47:51.288 11791 11835 I a2dp_codec: init: initialized Source codec SBC, idx 0
09-12 09:47:51.288 11791 11835 I a2dp_codec: init: initialized Sink codec Opus SINK, idx 14
09-12 09:47:51.288 11791 11835 I a2dp_codec: init: initialized Sink codec AAC SINK, idx 12
09-12 09:47:51.288 11791 11835 I a2dp_codec: init: initialized Sink codec SBC SINK, idx 11
09-12 09:47:51.288 11791 11835 W btm_acl : BTM_ReadRemoteFeatures: Unable to find active acl
09-12 09:47:51.288 11791 11835 I bt_bta_av: bta_av_cleanup: peer xx:xx:xx:xx:16:d6
09-12 09:47:51.288 11791 11835 I bt_bta_av: SetAvdtpVersion: AVDTP version for xx:xx:xx:xx:16:d6 set to 0x0
09-12 09:47:51.289 11791 11835 I bt_bta_av: SetAvdtpVersion: AVDTP version for 00:00:00:00:00:00 set to 0x0
09-12 09:47:51.289 11791 11835 I bt_bta_av: bta_av_rc_create: Skipping RC creation for the old AVRCP profile
09-12 09:47:51.289 11791 11835 I btif_av : btif_av_acl_disconnected: Peer xx:xx:xx:xx:16:d6 : ACL Disconnected
09-12 09:47:51.289 11791 11835 I btif_av : ProcessEvent: Peer xx:xx:xx:xx:16:d6 : event=BTA_AV_STOP_EVT(0x5) flags=0x0(None)
09-12 09:47:51.289 11791 11835 I bt_btif_a2dp: btif_a2dp_on_stopped: ## ON A2DP STOPPED ## p_av_suspend=0x900ceaf69832960
09-12 09:47:51.289 11791 11835 I bt_btif_a2dp_source: btif_a2dp_source_on_stopped: state=STATE_RUNNING
09-12 09:47:51.289 11791 11835 I bt_stack: [INFO:a2dp_encoding_aidl.cc(647)] ack_stream_suspended: result=SUCCESS_FINISHED
09-12 09:47:51.289 11791 11835 W bt_stack: [WARNING:a2dp_encoding_aidl.cc(656)] ack_stream_suspended: pending=0 ignore result=SUCCESS_FINISHED
09-12 09:47:51.289 11791 11835 I btif_av : btif_report_audio_state: peer_address=xx:xx:xx:xx:16:d6 state=1
09-12 09:47:51.290 11791 11848 I bt_btif_dm: btif_dm_get_connection_state: Acl is not connected to peer:xx:xx:xx:xx:16:d6
09-12 09:47:51.290 11791 11835 I btif_av : btif_report_connection_state: peer_address=xx:xx:xx:xx:16:d6 state=3
09-12 09:47:51.290 11791 11835 I bt_btif_a2dp: btif_a2dp_on_stopped: ## ON A2DP STOPPED ## p_av_suspend=0x0
09-12 09:47:51.290 11791 11835 I bt_btif_a2dp_source: btif_a2dp_source_on_stopped: state=STATE_RUNNING
09-12 09:47:51.290 11791 11835 I bt_stack: [INFO:a2dp_encoding_aidl.cc(647)] ack_stream_suspended: result=SUCCESS_FINISHED
09-12 09:47:51.290 11791 11835 W bt_stack: [WARNING:a2dp_encoding_aidl.cc(656)] ack_stream_suspended: pending=0 ignore result=SUCCESS_FINISHED
09-12 09:47:51.290 11791 11835 I btif_av : btif_report_connection_state: peer_address=xx:xx:xx:xx:16:d6 state=0
09-12 09:47:51.290 11791 11835 I bt_btif_a2dp_source: btif_a2dp_source_on_idle: state=STATE_RUNNING
09-12 09:47:51.290 11791 11835 I bt_btif_a2dp_source: btif_a2dp_source_stop_audio_req: state=STATE_RUNNING
09-12 09:47:51.290 11791 11835 I btif_av : SetActivePeer: peer: 00:00:00:00:00:00
09-12 09:47:51.290 11791 11866 I bt_btif_a2dp_source: btif_a2dp_source_audio_tx_stop_event: streaming true state=STATE_RUNNING
09-12 09:47:51.290 11791 11835 I bluetooth: SetActivePeer: peer_address=00:00:00:00:00:00
09-12 09:47:51.290 11791 11835 I bt_btif_a2dp_source: btif_a2dp_source_end_session: peer_address=xx:xx:xx:xx:16:d6 state=STATE_RUNNING
09-12 09:47:51.290 11791 11835 I bt_btif_a2dp_source: btif_a2dp_source_cleanup_codec: state=STATE_RUNNING
09-12 09:47:51.290 11791 11835 I bt_btif_a2dp_source: btif_a2dp_source_stop_audio_req: state=STATE_RUNNING
09-12 09:47:51.290 11791 11835 I bt_btif_a2dp_source: btif_a2dp_source_shutdown: state=STATE_RUNNING
09-12 09:47:51.292 11791 11848 I bt_btif_dm: btif_dm_get_connection_state: Acl is not connected to peer:xx:xx:xx:xx:16:d6
09-12 09:47:51.298 11791 11848 I BluetoothA2dpServiceJni: bta2dp_audio_state_callback:
09-12 09:47:51.298 11791 11848 I BluetoothA2dpServiceJni: bta2dp_connection_state_callback:
09-12 09:47:51.298 11791 11848 I BluetoothA2dpServiceJni: bta2dp_connection_state_callback:
09-12 09:47:51.298 11791 11864 I A2dpStateMachine: Connected: stopped playing: XX:XX:XX:XX:16:D6
09-12 09:47:51.302 11791 11866 W bt_stack: [WARNING:client_interface_aidl.cc(571)] ReadAudioData: 5120/28672 no data 10 ms
09-12 09:47:51.302 11791 11866 I bt_stack: [INFO:a2dp_encoding_aidl.cc(647)] ack_stream_suspended: result=SUCCESS_FINISHED
09-12 09:47:51.302 11791 11866 W bt_stack: [WARNING:a2dp_encoding_aidl.cc(656)] ack_stream_suspended: pending=0 ignore result=SUCCESS_FINISHED
09-12 09:47:51.302 11791 11866 I a2dp_vendor_ldac_encoder: a2dp_vendor_ldac_feeding_reset: PCM bytes per tick 15360
09-12 09:47:51.302 11791 11866 I bt_btif_a2dp_source: btif_a2dp_source_end_session_delayed: peer_address=xx:xx:xx:xx:16:d6 state=STATE_SHUTTING_DOWN
09-12 09:47:51.302 11791 11866 I btif_av : btif_av_stream_stop: peer xx:xx:xx:xx:16:d6
09-12 09:47:51.305 11791 11866 I bt_btif_a2dp_source: btif_a2dp_source_audio_tx_stop_event: streaming false state=STATE_SHUTTING_DOWN
09-12 09:47:51.305 11791 11866 I bt_btif_a2dp_source: btif_a2dp_source_cleanup_codec_delayed: state=STATE_SHUTTING_DOWN
09-12 09:47:51.305 11791 11866 I bt_btif_a2dp_source: btif_a2dp_source_shutdown_delayed: state=STATE_SHUTTING_DOWN
09-12 09:47:51.305 11791 11866 I bt_stack: [INFO:client_interface_aidl.cc(493)] EndSession: session ended already
09-12 09:47:51.306 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.306 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.306 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.306 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.306 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.306 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.306 11791 11835 I btif_av : DeleteIdlePeers: Deleting idle peer: xx:xx:xx:xx:16:d6 bta_handle=0x41
09-12 09:47:51.310 11791 11864 I A2dpStateMachine: Disconnecting from XX:XX:XX:XX:16:D6
09-12 09:47:51.311 11791 11864 I A2dpStateMachine: Enter Disconnecting(XX:XX:XX:XX:16:D6): STACK_EVENT
09-12 09:47:51.311 11791 11859 E BtGatt.ScanManager: received an unkown message : 12
09-12 09:47:51.312 11791 11791 I BluetoothPhonePolicy: processProfileStateChanged, device=XX:XX:XX:XX:16:D6, profile=A2DP, 2 -> 3
09-12 09:47:51.314 11791 11852 I bluetooth: SendMediaUpdate: track_changed=false : play_state=true : queue=false
09-12 09:47:51.318 11791 11864 I A2dpStateMachine: Disconnected: XX:XX:XX:XX:16:D6
09-12 09:47:51.318 11791 11864 I A2dpStateMachine: Enter Disconnected(XX:XX:XX:XX:16:D6): STACK_EVENT
09-12 09:47:51.332 11791 11791 I BluetoothUtils: Dual mode enable state is: false
09-12 09:47:51.333 11791 11791 I BluetoothPhonePolicy: processActiveDeviceChanged, device=null, profile=A2DP isDualModeAudioEnabled=false
09-12 09:47:51.333 11791 11835 I bluetooth: DisconnectDevice: address=xx:xx:xx:xx:16:d6
09-12 09:47:51.334 11791 11791 I BluetoothPhonePolicy: processProfileStateChanged, device=XX:XX:XX:XX:16:D6, profile=A2DP, 3 -> 0
09-12 09:47:51.334 11791 11791 W LeAudioService: getLeAudioService(): service is NULL
09-12 09:47:51.334 11791 11791 W CsipSetCoordinatorService: getCsipSetCoordinatorService(): service is NULL
09-12 09:47:51.334 11791 11791 I BluetoothPhonePolicy: handleAllProfilesDisconnected: all profiles disconnected for XX:XX:XX:XX:16:D6
09-12 09:47:51.334 11791 11791 I BluetoothPhonePolicy: handleAllProfilesDisconnected: all profiles disconnected for all devices
09-12 09:47:51.335 11791 11859 E BtGatt.ScanManager: received an unkown message : 12
09-12 09:47:51.348 11791 11852 I BluetoothA2dpServiceJni: setActiveDeviceNative: sBluetoothA2dpInterface: 0xce7d7da08198
09-12 09:47:51.348 11791 11835 I btif_av : SetActivePeer: peer: 00:00:00:00:00:00
09-12 09:47:51.348 11791 11835 E btif_av : set_active_peer_int: Error setting 00:00:00:00:00:00 as active Sink peer
09-12 09:47:51.359 11791 13082 I BluetoothAdapterService: getActiveDevices: A2dp device: null
09-12 09:47:51.367 11791 13082 I BluetoothAdapterService: getActiveDevices: Headset device: null
09-12 09:47:51.371 11791 13082 I BluetoothAdapterService: getActiveDevices: Hearing Aid devices: Left[null] - Right[null]
09-12 09:47:51.377 11791 13082 I BluetoothAdapterService: getActiveDevices: A2dp device: null
09-12 09:47:51.380 11791 13082 I BluetoothAdapterService: getActiveDevices: Headset device: null
09-12 09:47:51.382 11791 13082 I BluetoothAdapterService: getActiveDevices: Hearing Aid devices: Left[null] - Right[null]
09-12 09:47:51.524 11791 11791 W AudioMediaPlayerWrapper: onQueueChanged(): com.spotify.music tried to update with no new data
09-12 09:47:51.533 11791 11791 I bluetooth: SendMediaUpdate: track_changed=false : play_state=true : queue=false
09-12 09:47:51.651 11791 11840 I bluetooth: packages/modules/Bluetooth/system/gd/hci/acl_manager/classic_impl.h:680 - on_role_change: Role change for xx:xx:xx:xx:16:d6 with no matching connection (new role: CENTRAL)
09-12 09:47:51.655 838 1570 I bthal.activity: CreateConnectCompleteEvent, conn_handle:00B, conn_bda: XX:XX:ed:04:16:d6
09-12 09:47:51.656 11791 11840 I bluetooth: packages/modules/Bluetooth/system/gd/hci/acl_manager/classic_impl.h:356 - operator(): Sending delayed role change for xx:xx:xx:xx:16:d6
09-12 09:47:51.657 11791 11835 I btm_acl : change_connection_packet_types: Unable to include remote supported packet types as read feature incomplete
09-12 09:47:51.657 11791 11835 I btm_acl : change_connection_packet_types: TIP: Maybe wait until read feature complete beforehand
09-12 09:47:51.657 11791 11835 W btm_acl : BTM_SetLinkSuperTout: Role is peripheral so unable to set supervision timeout:80.00ms bd_addr:xx:xx:xx:xx:16:d6
09-12 09:47:51.657 11791 11835 I bt_bta_dm: bta_dm_acl_up: Acl connected peer:xx:xx:xx:xx:16:d6 transport:BT_TRANSPORT_BR_EDR handle:11
09-12 09:47:51.657 11791 11835 W btm_acl : acl_peer_supports_sniff_subrating: Checking remote features but remote feature read is incomplete
09-12 09:47:51.657 11791 11835 W btm_acl : BTM_ReadRemoteVersion: Remote version information is invalid
09-12 09:47:51.657 11791 11835 I bt_btif_dm: btif_update_remote_version_property: Remote version info valid:false [xx:xx:xx:xx:16:d6]:0x0,0x0,0x0
09-12 09:47:51.657 11791 11835 I bt_bta_dm: handle_role_change: Role change callback peer:xx:xx:xx:xx:16:d6 info:0x00:none new_role:central dev count:1 hci_status:HCI_SUCCESS
09-12 09:47:51.657 11791 11835 I bt_bta_dm: bta_dm_check_av: av_count:2
09-12 09:47:51.657 11791 11835 W bt_bta_dm: bta_dm_check_av: [0]: state:1, info:0x00:none
09-12 09:47:51.658 11791 11791 I BluetoothPhonePolicy: processDeviceConnected, device=XX:XX:XX:XX:16:D6
09-12 09:47:51.659 11791 11835 E bt_btu_hcif: btu_hcif_hdl_command_complete: Command complete for opcode:0x80d should not be handled here
09-12 09:47:51.663 11791 11840 I bluetooth: packages/modules/Bluetooth/system/gd/hci/hci_layer.cc:125 - drop: Dropping event MAX_SLOTS_CHANGE
09-12 09:47:51.665 11791 11835 I btm_acl : disconnect_acl: Disconnecting peer:xx:xx:xx:xx:16:d6 reason:HCI_ERR_AUTH_FAILURE comment:attempted to downgrade from Secure Connections mode
09-12 09:47:51.667 11791 11835 E bt_btu_hcif: btu_hcif_hdl_command_complete: Command complete for opcode:0x80d should not be handled here
09-12 09:47:51.745 11791 11835 I bt_shim_hci: packages/modules/Bluetooth/system/main/shim/hci_layer.cc:299 - operator(): ISO disconnection from GD, handle: 0x0b, reason: 0x16
09-12 09:47:51.745 11791 11835 I btif_av : btif_av_acl_disconnected: Peer xx:xx:xx:xx:16:d6 : ACL Disconnected
09-12 09:47:51.745 11791 11835 I btif_av : FindOrCreatePeer: Create peer: peer_address=xx:xx:xx:xx:16:d6 bta_handle=0x41 peer_id=0
09-12 09:47:51.745 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.745 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.745 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.745 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.745 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.746 11791 11835 I btif_av : btif_av_get_peer_sep: No active sink or source peer found
09-12 09:47:51.746 11791 11835 I btif_av : DeleteIdlePeers: Deleting idle peer: xx:xx:xx:xx:16:d6 bta_handle=0x41
09-12 09:47:51.752 11791 11848 I bt_btif_dm: btif_dm_get_connection_state: Acl is not connected to peer:xx:xx:xx:xx:16:d6
09-12 09:47:51.753 11791 11848 I bt_btif_dm: btif_dm_get_connection_state: Acl is not connected to peer:xx:xx:xx:xx:16:d6
09-12 09:47:51.836 11791 11820 W System : A resource failed to call close.
09-12 09:47:52.657 838 11845 I bthal.activity: bt_power: AclDataStat: 11:43:07:941 - 11:47:52:657, conn_handle: 11, duration: 284716, acl_data_count: 52772, acl_tx_data_count: 52752, acl_rx_data_count: 20, total_acl_data_count: 52838
09-12 09:47:53.022 838 1765 I bthal.activity: bt_power: TxWakeLockStat: 11:43:02:619 - 11:47:53:022, duration: 290402, tx_packet_count: 52913, total_tx_packet_count: 106883
09-12 09:47:58.291 11791 11835 I bluetooth: bta_dm_pm_set_mode: Device list count is zero
09-12 09:48:13.149 838 1570 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xe Opcode 0xfd59 Length 22 unhandled by ControllerActivityWatcher
09-12 09:48:13.152 838 838 I bthal.activitywatcher: getStateResidency: ActivityStat{0 : totalTimeMs 24021 totalEntryCount 60 lastEntryMs 53216}
09-12 09:48:13.152 838 838 I bthal.activitywatcher: getStateResidency: ActivityStat{1 : totalTimeMs 181404 totalEntryCount 65 lastEntryMs 53410}
09-12 09:48:13.152 838 838 I bthal.activitywatcher: getStateResidency: ActivityStat{2 : totalTimeMs 94622 totalEntryCount 34950 lastEntryMs 1147613}
09-12 09:48:13.152 838 838 I bthal.activitywatcher: getStateResidency: ActivityStat{3 : totalTimeMs 14964 totalEntryCount 38270 lastEntryMs 1147613}
09-12 09:48:13.152 838 838 I bthal.activitywatcher: send_hci_activities_vsc: Sent VSC <[4]{89, 253, 1, 1}>
09-12 09:48:13.153 838 1570 I bthal.activitywatcher: filter_hci_activities_vse: Filtered <[103]{14, 101, 1, 89, 253, 0, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0}>
09-12 09:48:13.153 838 1570 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xe Opcode 0xfd59 Length 103 handled by ControllerActivityWatcher
09-12 09:48:16.382 11791 11791 I bluetooth: SendMediaUpdate: track_changed=false : play_state=false : queue=false
09-12 09:48:16.458 11791 11791 I bluetooth: SendMediaUpdate: track_changed=false : play_state=false : queue=false
These lines in particular look interesting to me:
09-12 09:47:51.270 838 1570 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xff Opcode 0 Length 242 unhandled by debug_VendorEvent
09-12 09:47:51.272 11791 11835 I devctl : btm_vendor_specific_evt: Unhandled BQR subevent 0x58x
09-12 09:47:51.273 11791 11835 I bt_shim_hci: packages/modules/Bluetooth/system/main/shim/hci_layer.cc:299 - operator(): ISO disconnection from GD, handle: 0x0b, reason: 0x22
I have "always show crash dialog" enabled and got no crashes, the screen was not turned off for this test and I used spotify for playback. None of that seems to make a difference to the disconnects in my experience, so this is likely separate from other issues on bluetooth. The audeze HQ app for managing the headset is installed atm, but removing it didn't make any difference in the past. I also fiddled around in the dev settings (disabled BT LE audio, disabled A2DP HW offloading and switching codecs from LDAC to AAC and SBC) without any success.
I've also taken an unfiltered HCI snoop, but I don't know what PII that contains so I'm hesitant on posting it publicly. I can gather more data if needed.
It's either a bug in the upstream Bluetooth code or an issue with the device which happens to get handled better by the older Bluetooth code and other platforms. Looks a lot like the current Bluetooth device not following the protocol properly which causes a disconnection. It might have stricter handling of this specific case.
I'll have access to a stock Pixel 8 in 2 weeks and will test it with that to narrow it down.
The logs should be directly comparable, the code in the GOS log viewer is pretty clear even without knowing much java so I can avoid reading the logcat docs once more.
The issue still occurs on 2024102400.
I did do some testing on the stock Pixel 8 and listened to music for about 45 minutes with logcat running, then when I started writing my reply and didn't have logcat running, the headset disconnected. I didn't have time to do more testing, but I did notice that the stock pixel 8 has the com.android.bluetooth package listed as 'Bluetooth legacy' and there's also com.google.android.bluetooth listed as 'Bluetooth'. I plan on doing some more testing if I get the chance, are both of those relevant or just com.android.bluetooth?
This is not a pixel or android issue it is with the device firmware itself I believe, I've had it disconnect in a similar manner on all types of devices, Windows, Linux, OSX, ios, the same issue persists with or without the use of the donggle. I'm totally sick of it to be honest, and if anyone wants to get a copy of the firmware for the device and create a repo we can work on together I would be more than happy to contribute as I am a low-level software engineer with heavy experience in C/C++. I belive we could find and implement a solution to fix the issues relatively easily, I'm not sure why the Adunze Devs can't sort it out their customers have been complaining about this for over 3 firmware releases.
I've been experiencing a similar bug for the past few months but with my car (not Android Auto, just regular Bluetooth). It used to work perfectly, I believe it broke with Android 15 (I have a Pixel 8). I have no issue at all with regular (more recent) bluetooth speakers, so I guess it is either a broken hardware or some retro-compatibility code that got removed(/is now broken) with Android 15.
After the crash happens, the phone automatically reconnects to the car ~15 seconds later.
Here are some system error logs I was able to capture around the time the disconnect happened (CLICK)
07-12 12:06:06.110 1002 14225 14265 E BluetoothRemoteDevices: encryptionChangeCallback: device is NULL, address=XX:XX:XX:XX:00:00
07-12 12:06:06.163 1002 14225 14254 E bt_btu_hcif: system/stack/btu/btu_hcif.cc:986 btu_hcif_hdl_command_complete: Command complete for opcode:0x80d should not be handled here
07-12 12:06:06.168 1002 14225 14254 I bluetooth: system/bta/le_audio/client.cc:6263 le_audio_gattc_callback: event = 25
07-12 12:06:06.173 1002 14225 14254 I bluetooth: system/bta/vc/vc.cc:1503 gattc_callback: event = 25
07-12 12:06:06.173 1002 14225 14254 E bluetooth-asha: system/bta/hearing_aid/hearing_aid.cc:726 OnPhyUpdateEvent: unknown device: conn_id=0x2f
07-12 12:06:06.175 1002 14225 14254 I bluetooth: system/bta/csis/csis_client.cc:1840 GattcCallback: event = 0x19
07-12 12:06:06.504 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:06.504 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:07.012 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:07.013 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:07.101 1002 14225 14254 I bluetooth: system/bta/le_audio/client.cc:6263 le_audio_gattc_callback: event = 26
07-12 12:06:07.101 1002 14225 14254 I bluetooth: system/bta/vc/vc.cc:1503 gattc_callback: event = 26
07-12 12:06:07.102 1002 14225 14254 E bluetooth-asha: system/bta/hearing_aid/hearing_aid.cc:590 OnConnectionUpdateComplete: unknown device: conn_id=0x2f
07-12 12:06:07.102 1002 14225 14254 I bluetooth: system/bta/csis/csis_client.cc:1840 GattcCallback: event = 0x1a
07-12 12:06:07.518 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:07.519 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:08.028 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:08.029 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:08.153 1002 14225 14259 I bluetooth: system/gd/hci/le_address_manager.cc:289 unregister_client: Client unregistered
07-12 12:06:08.156 1002 14225 14259 I bluetooth: system/gd/hci/le_scanning_manager.cc:597 stop_scan: Scanning already stopped, return!
07-12 12:06:08.157 1002 14225 14259 I bluetooth: system/gd/hci/le_address_manager.cc:274 register_client: Client registered
07-12 12:06:08.178 10109 2398 2866 E PixelDisplayManager: setLbeState to 1
07-12 12:06:08.185 1041 959 990 D AHAL_Module: updateScreenState: bluetooth: 1
07-12 12:06:08.201 1000 1389 1902 E AppOps : attributionTag IWLAN not declared in manifest of com.google.android.iwlan
07-12 12:06:08.217 1000 642 648 E android.system.suspend-service: Error opening kernel wakelock stats for: wakeup101 (../../devices/platform/odm/odm:fps_touch_handler/wakeup/wakeup101): Permission denied
07-12 12:06:08.335 1041 959 990 D AHAL_Module: updateScreenState: bluetooth: 1
07-12 12:06:08.344 1000 657 657 E Scheduler: trackPendingFrame: Invalid present fence
07-12 12:06:08.466 10136 15131 15131 E receiver.module: Not starting debugger since process cannot load the jdwp agent.
07-12 12:06:08.530 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:08.531 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:08.761 10136 15131 15131 E cutils-trace: Error opening trace file: No such file or directory (2)
07-12 12:06:09.038 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:09.039 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:09.164 10136 15131 15131 E com.android.cellbroadcastreceiver.module: Unable to find pattern file or unable to map it for am
07-12 12:06:09.525 1041 959 5788 D AHAL_Module: updateScreenState: bluetooth: 1
07-12 12:06:09.546 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:09.547 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:09.609 1000 4908 4916 E [HAL][HalUtils]: [readStr] failed to read '/sys/devices/platform/exynos-drm/primary-panel/op_hz', errno = 22
07-12 12:06:09.609 1000 4908 4916 E [HAL][HalUtils]: [getDisplayOpRate] Failed to read file
07-12 12:06:09.706 1000 4908 4916 E [GF_HAL][CustomizedSensor]: [readImage] err=GF_SUCCESS, dwell_time=95
07-12 12:06:09.767 1000 1389 2102 E AidlResponseHandler: Client monitor is not an instance of com.android.server.biometrics.sensors.AcquisitionClient
07-12 12:06:09.777 1000 1389 2102 E FingerprintProvider/default: onPointerUp received during client: null
07-12 12:06:09.792 10121 2136 2136 E KeyguardViewMediator: mHideAnimationFinishedRunnable#run
07-12 12:06:09.794 10109 2398 2866 E PixelDisplayManager: setLbeState to 0
07-12 12:06:09.805 1041 959 990 D AHAL_Module: updateScreenState: bluetooth: 1
07-12 12:06:10.055 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:10.056 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:10.148 1000 4908 4916 E [GF_HAL][CustomizedDumpKPI]: [parseAuthBigData] ui_ready_latency_ms=126, post_auth_latency_ms=15
07-12 12:06:10.311 1000 1389 1418 E BiometricScheduler: Current operation is null,no need to start watchdog
07-12 12:06:10.563 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:10.563 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:11.072 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:11.072 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:14.369 1002 14225 14259 I bluetooth: system/gd/hci/acl_manager/round_robin_scheduler.cc:74 Unregister: unregistering acl_queue handle=65, sent_packets=1
07-12 12:06:14.371 1002 14225 14254 I bluetooth: system/stack/arbiter/acl_arbiter.cc:59 OnLeDisconnect: Notifying Rust of LE disconnection
07-12 12:06:14.371 1002 14225 14254 I bluetooth: system/stack/eatt/eatt_impl.h:883 disconnect: Device: xx:xx:xx:xx:0e:99, cid: 0xffff
07-12 12:06:14.371 1002 14225 14254 W bluetooth: system/stack/eatt/eatt_impl.h:887 disconnect: no eatt device found
07-12 12:06:14.372 1002 14225 14254 I bluetooth: system/stack/gap/gap_ble.cc:316 client_connect_cback: No active GAP service found for peer:xx:xx:xx:xx:0e:99 callback:Disconnected
07-12 12:06:14.372 1002 14225 14254 I bluetooth: system/stack/gatt/gatt_attr.cc:375 gatt_connect_cback: remove untrusted client status, bda=xx:xx:xx:xx:0e:99
07-12 12:06:14.372 1002 14225 14254 E bta_gattc_main: system/bta/gatt/bta_gattc_main.cc:332 bta_gattc_hdl_event: Ignore unknown conn ID: 53
07-12 12:06:14.372 1002 14225 14254 E bta_gattc_main: system/bta/gatt/bta_gattc_main.cc:332 bta_gattc_hdl_event: Ignore unknown conn ID: 51
07-12 12:06:14.372 1002 14225 14254 E bta_gattc_main: system/bta/gatt/bta_gattc_main.cc:332 bta_gattc_hdl_event: Ignore unknown conn ID: 48
07-12 12:06:14.372 1002 14225 14254 E bta_gattc_main: system/bta/gatt/bta_gattc_main.cc:332 bta_gattc_hdl_event: Ignore unknown conn ID: 47
07-12 12:06:14.372 1002 14225 14254 E bta_gattc_main: system/bta/gatt/bta_gattc_main.cc:332 bta_gattc_hdl_event: Ignore unknown conn ID: 46
07-12 12:06:14.372 1002 14225 14254 E bta_gattc_main: system/bta/gatt/bta_gattc_main.cc:332 bta_gattc_hdl_event: Ignore unknown conn ID: 45
07-12 12:06:14.372 1002 14225 14254 E bta_gattc_main: system/bta/gatt/bta_gattc_main.cc:332 bta_gattc_hdl_event: Ignore unknown conn ID: 44
07-12 12:06:14.631 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:14.632 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:15.137 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:15.138 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:15.643 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:15.643 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:16.149 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:16.149 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:16.657 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:16.657 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:17.165 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:17.165 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:25.804 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:25.881 10051 15188 15188 E oid.documentsui: Not starting debugger since process cannot load the jdwp agent.
07-12 12:06:26.069 10051 15188 15188 E cutils-trace: Error opening trace file: No such file or directory (2)
07-12 12:06:26.142 1002 14225 14254 E smp_act : system/stack/smp/smp_act.cc:2080 smp_cancel_start_encryption_attempt: Encryption request cancelled
07-12 12:06:26.144 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x02
07-12 12:06:26.144 1002 14225 14259 I bluetooth: system/gd/hci/acl_manager/round_robin_scheduler.cc:74 Unregister: unregistering acl_queue handle=11, sent_packets=4
07-12 12:06:26.144 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:51:6e, lmp_ver: 3, manufacturer_id: 10, lmp_subver: 4535
07-12 12:06:26.145 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_main.cc:401 bta_av_conn_cback: conn_cback bd_addr: xx:xx:xx:xx:51:6e, scb_index: 0
07-12 12:06:26.145 1002 14225 14254 I bluetooth: system/stack/rfcomm/rfc_mx_fsm.cc:70 rfc_mx_sm_execute: RFCOMM peer:xx:xx:xx:xx:51:6e event:RFC_MX_EVENT_DISC_IND state:RFC_MX_STATE_CONNECTED
07-12 12:06:26.146 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_ssm.cc:442 bta_av_ssm_execute: peer xx:xx:xx:xx:51:6e p_scb=0x41(0xb00cf7001b51b00) AV event=0x121e(STR_CLOSE) state=3(OPEN) -> 0(INIT)
07-12 12:06:26.146 1002 14225 14254 W bluetooth-a2dp: system/bta/av/bta_av_aact.cc:2458 bta_av_str_closed: peer xx:xx:xx:xx:51:6e bta_handle:0x41 open_status:0 chnl:64 co_started:1
07-12 12:06:26.146 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_aact.cc:1930 bta_av_str_stopped: peer xx:xx:xx:xx:51:6e bta_handle:0x41 audio_open_cnt:1, p_data 0x0 start:1
07-12 12:06:26.146 1002 14225 14254 I bluetooth: system/bta/dm/bta_dm_pm.cc:909 bta_dm_pm_ssr: Found connected service:BTA_ID_AG app_id:0 peer:xx:xx:xx:xx:51:6e spec_name:sniff_capable
07-12 12:06:26.146 1002 14225 14254 I bluetooth: system/bta/dm/bta_dm_pm.cc:909 bta_dm_pm_ssr: Found connected service:BTA_ID_AV app_id:0 peer:xx:xx:xx:xx:51:6e spec_name:sniff_capable
07-12 12:06:26.146 1002 14225 14254 I bluetooth: system/bta/dm/bta_dm_pm.cc:909 bta_dm_pm_ssr: Found connected service:BTA_ID_AV app_id:1 peer:xx:xx:xx:xx:51:6e spec_name:sniff_capable
07-12 12:06:26.146 1002 14225 14254 W bluetooth: system/bta/dm/bta_dm_pm.cc:960 bta_dm_pm_ssr: Unable to set link into sniff mode peer:xx:xx:xx:xx:51:6e
07-12 12:06:26.146 1002 14225 14254 W bluetooth-a2dp: system/bta/av/bta_av_act.cc:1208 bta_av_stream_chg: Unable to set L2CAP acl normal priority peer:xx:xx:xx:xx:51:6e
07-12 12:06:26.146 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:655 init:
07-12 12:06:26.146 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: SBC
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:692 init: updated SBC codec priority to 1001
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: AAC
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:692 init: updated AAC codec priority to 2001
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: aptX
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:692 init: updated aptX codec priority to 3001
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: aptX-HD
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:692 init: updated aptX-HD codec priority to 4001
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: LDAC
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:692 init: updated LDAC codec priority to 5001
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: LC3 not implemented
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: Opus
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:692 init: updated Opus codec priority to 7001
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: UNKNOWN CODEC INDEX
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: UNKNOWN CODEC INDEX
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: UNKNOWN CODEC INDEX
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: UNKNOWN CODEC INDEX
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: SBC SINK
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: AAC SINK
07-12 12:06:26.147 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: LDAC SINK
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_vendor_ldac_decoder.cc:128 A2DP_VendorLoadDecoderLdac: cannot open LDAC decoder library libldacBT_bco.so: dlopen failed: library "libldacBT_bco.so" not found
07-12 12:06:26.148 1002 14225 14254 E bluetooth-a2dp: system/stack/a2dp/a2dp_vendor_ldac.cc:1333 init: cannot load the decoder
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: Opus SINK
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: UNKNOWN CODEC INDEX
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: UNKNOWN CODEC INDEX
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: UNKNOWN CODEC INDEX
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:162 createCodec: UNKNOWN CODEC INDEX
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:716 init: initialized Source codec Opus, idx 6
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:716 init: initialized Source codec LDAC, idx 4
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:716 init: initialized Source codec aptX-HD, idx 3
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:716 init: initialized Source codec aptX, idx 2
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:716 init: initialized Source codec AAC, idx 1
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:716 init: initialized Source codec SBC, idx 0
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:723 init: initialized Sink codec Opus SINK, idx 14
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:723 init: initialized Sink codec AAC SINK, idx 12
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_codec_config.cc:723 init: initialized Sink codec SBC SINK, idx 11
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_aact.cc:846 bta_av_cleanup: peer xx:xx:xx:xx:51:6e
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_main.cc:373 SetAvdtpVersion: AVDTP version for xx:xx:xx:xx:51:6e set to 0x0
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_main.cc:373 SetAvdtpVersion: AVDTP version for 00:00:00:00:00:00 set to 0x0
07-12 12:06:26.148 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_act.cc:344 bta_av_rc_create: Skipping RC creation for the old AVRCP profile
07-12 12:06:26.149 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_sco.cc:756 bta_ag_sco_event: device:00:00:00:00:00:00 index:0xffff state:BTA_AG_SCO_LISTEN_ST[1] event:BTA_AG_SCO_SHUTDOWN_E[6]
07-12 12:06:26.149 1002 14225 14254 W bluetooth: system/bta/ag/bta_ag_sco.cc:1244 bta_ag_sco_event: SCO_state_change: [BTA_AG_SCO_LISTEN_ST(0x01)]->[BTA_AG_SCO_SHUTDOWN_ST(0x00)] after event [BTA_AG_SCO_SHUTDOWN_E(0x06)]
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_main.cc:969 bta_av_sco_chg_cback: status=BTA_SYS_SCO_CLOSE, num_links=0
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3949 btif_av_acl_disconnected: peer=xx:xx:xx:xx:51:6e
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3949 btif_av_acl_disconnected: peer=xx:xx:xx:xx:51:6e
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=00:00:00:00:00:00 handle=0x41 event=BTA_AV_STOP_EVT(0x5)
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2513 ProcessEvent: state=Started peer=xx:xx:xx:xx:51:6e event=BTA_AV_STOP_EVT(0x5) flags=0x0(None) active_peer=true
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2627 ProcessEvent: Peer xx:xx:xx:xx:51:6e : event=BTA_AV_STOP_EVT(0x5) flags=0x0(None)
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp.cc:98 btif_a2dp_on_stopped: ## ON A2DP STOPPED ## p_av_suspend=0x100ce4b5e221510
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:788 btif_a2dp_source_on_stopped: state=STATE_RUNNING
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:1077 btif_a2dp_source_audio_tx_flush_req: state=STATE_RUNNING
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:707 btif_a2dp_source_stop_audio_req: state=STATE_RUNNING
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2909 btif_report_audio_state: peer=xx:xx:xx:xx:51:6e state=1
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2507 OnExit: state=Started peer=xx:xx:xx:xx:51:6e
07-12 12:06:26.149 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2223 OnEnter: state=Opened peer=xx:xx:xx:xx:51:6e
07-12 12:06:26.149 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1088 vendor_specific_event_callback: Unhandled BQR subevent 0x03
07-12 12:06:26.149 1002 14225 14254 I bluetooth: system/btif/src/btif_bqr.cc:1027 bqr_delivery_event: len: 245, addr: xx:xx:xx:xx:6b:b2, lmp_ver: 0, manufacturer_id: 0, lmp_subver: 0
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=00:00:00:00:00:00 handle=0x41 event=BTA_AV_CLOSE_EVT(0x3)
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2250 ProcessEvent: state=Opened peer=xx:xx:xx:xx:51:6e event=BTA_AV_CLOSE_EVT(0x3) flags=0x0(None) active_peer=true
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2869 btif_report_connection_state: peer=xx:xx:xx:xx:51:6e state=3
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp.cc:98 btif_a2dp_on_stopped: ## ON A2DP STOPPED ## p_av_suspend=0x0
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:788 btif_a2dp_source_on_stopped: state=STATE_RUNNING
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:1077 btif_a2dp_source_audio_tx_flush_req: state=STATE_RUNNING
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:707 btif_a2dp_source_stop_audio_req: state=STATE_RUNNING
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2869 btif_report_connection_state: peer=xx:xx:xx:xx:51:6e state=0
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2242 OnExit: state=Opened peer=xx:xx:xx:xx:51:6e
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1650 OnEnter: state=Idle peer=xx:xx:xx:xx:51:6e
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:778 btif_a2dp_source_on_idle: state=STATE_RUNNING
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:707 btif_a2dp_source_stop_audio_req: state=STATE_RUNNING
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:518 SetActivePeer: peer=00:00:00:00:00:00 active_peer=xx:xx:xx:xx:51:6e
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:525 SetActivePeer: peer address is empty, shutdown the Audio source
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/co/bta_av_co.cc:659 SetActivePeer: peer_address=00:00:00:00:00:00
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:534 btif_a2dp_source_end_session: peer_address=xx:xx:xx:xx:51:6e state=STATE_RUNNING
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:686 btif_a2dp_source_cleanup_codec: state=STATE_RUNNING
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:707 btif_a2dp_source_stop_audio_req: state=STATE_RUNNING
07-12 12:06:26.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:568 btif_a2dp_source_shutdown: state=STATE_RUNNING
07-12 12:06:26.152 1002 14225 14265 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:89 bta2dp_audio_state_callback: xx:xx:xx:xx:51:6e: state: BTAV_AUDIO_STATE_STOPPED(0x1)
07-12 12:06:26.153 1002 14225 14265 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:67 bta2dp_connection_state_callback: xx:xx:xx:xx:51:6e: state: BTAV_CONNECTION_STATE_DISCONNECTING(0x3)
07-12 12:06:26.153 1002 14225 14265 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:67 bta2dp_connection_state_callback: xx:xx:xx:xx:51:6e: state: BTAV_CONNECTION_STATE_DISCONNECTED(0x0)
07-12 12:06:26.165 1000 1389 2323 I Telecom : CallAudioRouteController: bluetooth route removed: AudioRoute[Type=TYPE_BLUETOOTH_SCO, Address=E0:75:0A:7F:51:6E]: BSR.oR->CARC.pM_BT_DEVICE_REMOVED@rPw
07-12 12:06:26.165 1002 14225 14269 E LeAudioService: handleGroupTransitToInactive: no descriptors for group: -1 or group already inactive
07-12 12:06:26.174 1041 959 990 I AHal::BT: DisconnectDevice: bluetooth-sco-headset
07-12 12:06:26.280 1041 959 5788 I AHal::PortRouteManager: UpdateOrAddPatch: update patch 754, AudioPatch{id: 754, source: [400002/2: deep-buffer-playback], sink: [401187/300162: bluetooth-a2dp-device], min frames: 1920, latencies: [180]}
07-12 12:06:26.282 1002 14225 14334 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:26.291 1000 1389 2323 E AudioSystem: Function: getDevicesForRoleAndStrategy Line: 2573 Failed
07-12 12:06:26.291 1000 1389 2323 E AudioSystem-JNI: Command failed for android_media_AudioSystem_getDevicesForRoleAndStrategy: -2
07-12 12:06:26.291 1000 1389 2323 E AS.AudioService: Error 1 in getPreferredDeviceForStrategy(0)
07-12 12:06:26.300 1041 959 5788 I AHal::BT: DisconnectDevice: bluetooth-sco-headset-microphones
07-12 12:06:26.334 1002 14225 14269 I bluetooth: system/btif/avrcp/avrcp_service.cc:572 SendMediaUpdate: track_changed=false : play_state=true : queue=false
07-12 12:06:26.341 1002 14225 14269 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:424 setActiveDeviceNative: 00:00:00:00:00:00
07-12 12:06:26.341 1002 14225 14269 I bluetooth-a2dp: system/btif/src/btif_av.cc:3604 btif_av_source_set_active_device: peer=00:00:00:00:00:00
07-12 12:06:26.348 10051 15188 15188 E com.android.documentsui: Unable to find pattern file or unable to map it for am
07-12 12:06:26.359 1002 14225 14356 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:26.381 1002 14225 14259 I bluetooth: system/gd/hci/acl_manager/classic_impl.h:677 on_role_change: Role change for xx:xx:xx:xx:51:6e with no matching connection (new role: CENTRAL(0x00))
07-12 12:06:26.413 1000 1389 2072 E AppOps : package com.android.server.audio not found, can't check for attributionTag null
07-12 12:06:26.413 1000 1389 2072 E AppOps : Bad call made by uid 1000. Package "com.android.server.audio" does not belong to uid 1000.
07-12 12:06:26.414 1000 1389 2072 E AppOps : Cannot noteOperation: non-application UID 1000
07-12 12:06:26.414 1000 1389 1451 E ActivityManager: Unable to find UidObserver by token
07-12 12:06:26.488 1002 14225 14225 E MediaControlProfile: onCurrentPlayerStateUpdated: base actions not supported, player actions= 2e2bb4, expected at least= 7f
07-12 12:06:26.500 1002 14225 14225 I bluetooth: system/btif/avrcp/avrcp_service.cc:572 SendMediaUpdate: track_changed=false : play_state=true : queue=false
07-12 12:06:26.624 10035 15229 15229 E externalstorage: Not starting debugger since process cannot load the jdwp agent.
07-12 12:06:26.850 10035 15229 15229 E cutils-trace: Error opening trace file: No such file or directory (2)
07-12 12:06:26.881 1002 14225 14244 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:27.150 1002 14225 14254 E bluetooth-a2dp: system/btif/src/btif_av.cc:536 SetActivePeer: Timed out waiting for A2DP source shutdown to complete.
07-12 12:06:27.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_ACL_DISCONNECTED(0x20)
07-12 12:06:27.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1686 ProcessEvent: state=Idle peer=xx:xx:xx:xx:51:6e event=BTIF_AV_ACL_DISCONNECTED(0x20) flags=0x0(None) active_peer=false
07-12 12:06:27.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1682 OnExit: state=Idle peer=xx:xx:xx:xx:51:6e
07-12 12:06:27.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1650 OnEnter: state=Idle peer=xx:xx:xx:xx:51:6e
07-12 12:06:27.150 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.150 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.150 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_ACL_DISCONNECTED(0x20)
07-12 12:06:27.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1686 ProcessEvent: state=Idle peer=xx:xx:xx:xx:51:6e event=BTIF_AV_ACL_DISCONNECTED(0x20) flags=0x0(None) active_peer=false
07-12 12:06:27.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1682 OnExit: state=Idle peer=xx:xx:xx:xx:51:6e
07-12 12:06:27.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1650 OnEnter: state=Idle peer=xx:xx:xx:xx:51:6e
07-12 12:06:27.150 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.150 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.150 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:1061 btif_a2dp_source_audio_tx_flush_event: state=STATE_SHUTTING_DOWN
07-12 12:06:27.150 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:889 btif_a2dp_source_audio_tx_stop_event: streaming false state=STATE_SHUTTING_DOWN
07-12 12:06:27.151 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:1061 btif_a2dp_source_audio_tx_flush_event: state=STATE_SHUTTING_DOWN
07-12 12:06:27.151 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:889 btif_a2dp_source_audio_tx_stop_event: streaming false state=STATE_SHUTTING_DOWN
07-12 12:06:27.151 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:889 btif_a2dp_source_audio_tx_stop_event: streaming false state=STATE_SHUTTING_DOWN
07-12 12:06:27.151 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:542 btif_a2dp_source_end_session_delayed: peer_address=xx:xx:xx:xx:51:6e state=STATE_SHUTTING_DOWN
07-12 12:06:27.151 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3711 btif_av_stream_stop: peer=xx:xx:xx:xx:51:6e
07-12 12:06:27.152 1041 959 990 I BTAudioSessionAidl: ReportSessionStatus - SessionType=A2DP_HARDWARE_OFFLOAD_ENCODING_DATAPATH notify to bluetooth_audio=0x0200
07-12 12:06:27.153 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:889 btif_a2dp_source_audio_tx_stop_event: streaming false state=STATE_SHUTTING_DOWN
07-12 12:06:27.153 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:693 btif_a2dp_source_cleanup_codec_delayed: state=STATE_SHUTTING_DOWN
07-12 12:06:27.153 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:583 btif_a2dp_source_shutdown_delayed: state=STATE_SHUTTING_DOWN
07-12 12:06:27.153 1002 14225 14254 I bluetooth: system/bta/le_audio/client.cc:1580 GroupSetActive: group_id: -1
07-12 12:06:27.153 1002 14225 14254 I bluetooth: system/btif/avrcp/avrcp_service.cc:556 DisconnectDevice: address=xx:xx:xx:xx:51:6e
07-12 12:06:27.153 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:27.153 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1686 ProcessEvent: state=Idle peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=false
07-12 12:06:27.153 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:1951 ProcessEvent: Peer xx:xx:xx:xx:51:6e : Unhandled event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3488 set_active_peer_int: peer_sep=Sink peer=00:00:00:00:00:00
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:518 SetActivePeer: peer=00:00:00:00:00:00 active_peer=00:00:00:00:00:00
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1686 ProcessEvent: state=Idle peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=false
07-12 12:06:27.154 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:1951 ProcessEvent: Peer xx:xx:xx:xx:51:6e : Unhandled event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1686 ProcessEvent: state=Idle peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=false
07-12 12:06:27.154 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:1951 ProcessEvent: Peer xx:xx:xx:xx:51:6e : Unhandled event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1327 DeleteIdlePeers: peer=xx:xx:xx:xx:51:6e bta_handle=0x41
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_STOP_STREAM_REQ_EVT(0x1d)
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1256 FindOrCreatePeer: Create peer: peer=xx:xx:xx:xx:51:6e bta_handle=0x41 peer_id=0
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1650 OnEnter: state=Idle peer=xx:xx:xx:xx:51:6e
07-12 12:06:27.154 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.154 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.154 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1686 ProcessEvent: state=Idle peer=xx:xx:xx:xx:51:6e event=BTIF_AV_STOP_STREAM_REQ_EVT(0x1d) flags=0x0(None) active_peer=false
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1682 OnExit: state=Idle peer=xx:xx:xx:xx:51:6e
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1650 OnEnter: state=Idle peer=xx:xx:xx:xx:51:6e
07-12 12:06:27.154 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.154 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.154 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:27.154 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1327 DeleteIdlePeers: peer=xx:xx:xx:xx:51:6e bta_handle=0x41
07-12 12:06:27.154 10035 15229 15229 E com.android.externalstorage: Unable to find pattern file or unable to map it for am
07-12 12:06:27.156 1002 14225 14254 E bt_btu_hcif: system/stack/btu/btu_hcif.cc:986 btu_hcif_hdl_command_complete: Command complete for opcode:0x40b should not be handled here
07-12 12:06:27.156 1002 14225 14265 E BluetoothAdapterService: Cannot switch buffer size. The number of A2DP active devices is 0
07-12 12:06:27.156 1002 14225 14265 E BluetoothAdapterService: Cannot switch buffer size. The number of A2DP active devices is 0
07-12 12:06:27.156 1002 14225 14265 E LeAudioService: deviceDisconnected: no descriptors for group: -1
07-12 12:06:27.217 1002 14225 14259 I bluetooth: system/gd/hci/acl_manager/round_robin_scheduler.cc:56 Register: registering acl_queue handle=11, acl_credits=12, le_credits=15
07-12 12:06:27.217 1002 14225 14259 I bluetooth: system/gd/hci/acl_manager/round_robin_scheduler.cc:59 Register: start round robin
07-12 12:06:27.217 1002 14225 14259 I bluetooth: system/gd/hci/acl_manager/classic_impl.h:362 operator(): Sending delayed role change for xx:xx:xx:xx:51:6e
07-12 12:06:27.224 1002 14225 14254 E bt_btu_hcif: system/stack/btu/btu_hcif.cc:986 btu_hcif_hdl_command_complete: Command complete for opcode:0x80d should not be handled here
07-12 12:06:27.229 1002 14225 14259 I bluetooth: system/gd/hci/hci_layer.cc:164 drop: Dropping event MAX_SLOTS_CHANGE(0x1b)
07-12 12:06:27.232 1002 14225 14254 E bt_btu_hcif: system/stack/btu/btu_hcif.cc:986 btu_hcif_hdl_command_complete: Command complete for opcode:0x80d should not be handled here
07-12 12:06:27.235 1002 14225 14259 I bluetooth: system/main/shim/acl.cc:584 OnReadClockOffsetComplete: UNIMPLEMENTED
07-12 12:06:27.355 10190 14346 14513 E VLC : [0100c302e32697d0/38b1] libvlc services discovery: mDNS entry callback error: Operation not permitted
07-12 12:06:27.358 1041 959 5788 I AHal::DeviceInterface: Disconnect: disconnect deep-buffer-playback-0 -> bluetooth-a2dp-device
07-12 12:06:27.360 1041 959 5788 I AHal::AudioRoutePathController: ResetPath: deep-buffer-playback-0 -> bluetooth-a2dp-device: true
07-12 12:06:27.360 1041 959 5788 I AHal::Router: LogTransferring: deep-buffer-playback-0 -> bluetooth-a2dp-device => (null)
07-12 12:06:27.361 1041 959 5788 I AHal::BT: DisconnectDevice: bluetooth-a2dp-device
07-12 12:06:27.470 10051 15188 15188 E com.android.documentsui: No package ID ff found for resource ID 0xffffffff.
07-12 12:06:27.484 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.485 1041 959 1686 E AHal::TinyalsaDriverOperations: OpenAndPrepareLocked: pcm_prepare failed, error msg: cannot prepare channel: Invalid argument
07-12 12:06:27.492 10051 15188 15188 E com.android.documentsui: No package ID ff found for resource ID 0xffffffff.
07-12 12:06:27.510 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.528 1041 959 990 E AHAL_DownmixContext: validateCommonConfig: output should be stereo, not AudioChannelLayout{layoutMask: 63}
07-12 12:06:27.529 1041 959 990 E AHAL_EffectImpl: open: line92 return with expr !mImplContext
07-12 12:06:27.529 1041 1040 14609 E EffectConversionHelperAidl: Function: handleSetConfig Line: 192 Failed
07-12 12:06:27.529 1041 959 990 E AHAL_DownmixContext: validateCommonConfig: output should be stereo, not AudioChannelLayout{layoutMask: 63}
07-12 12:06:27.529 1041 959 990 E AHAL_EffectImpl: open: line92 return with expr !mImplContext
07-12 12:06:27.530 1041 1040 14609 E EffectConversionHelperAidl: Function: handleSetConfig Line: 192 Failed
07-12 12:06:27.530 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.531 1041 959 1686 E AHal::TinyalsaDriverOperations: OpenAndPrepareLocked: pcm_prepare failed, error msg: cannot prepare channel: Invalid argument
07-12 12:06:27.540 1041 959 990 E AHAL_EffectImpl: command: line271 return with expr mState == State::INIT
07-12 12:06:27.541 1041 959 959 E AHAL_EffectImpl: command: line271 return with expr mState == State::INIT
07-12 12:06:27.553 1002 14225 14259 I bluetooth: system/gd/hci/le_address_manager.cc:289 unregister_client: Client unregistered
07-12 12:06:27.555 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.563 10040 15269 15269 E d.process.media: Not starting debugger since process cannot load the jdwp agent.
07-12 12:06:27.576 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.577 1041 959 1686 E AHal::TinyalsaDriverOperations: OpenAndPrepareLocked: pcm_prepare failed, error msg: cannot prepare channel: Invalid argument
07-12 12:06:27.591 1002 14225 14254 I bluetooth: system/stack/rfcomm/rfc_mx_fsm.cc:70 rfc_mx_sm_execute: RFCOMM peer:xx:xx:xx:xx:51:6e event:RFC_MX_EVENT_CONN_IND state:RFC_MX_STATE_IDLE
07-12 12:06:27.591 1002 14225 14254 I bluetooth: system/stack/rfcomm/rfc_mx_fsm.cc:70 rfc_mx_sm_execute: RFCOMM peer:xx:xx:xx:xx:51:6e event:RFC_MX_EVENT_CONF_IND state:RFC_MX_STATE_CONFIGURE
07-12 12:06:27.591 1002 14225 14254 I bluetooth: system/stack/rfcomm/rfc_mx_fsm.cc:70 rfc_mx_sm_execute: RFCOMM peer:xx:xx:xx:xx:51:6e event:RFC_MX_EVENT_CONF_CNF state:RFC_MX_STATE_CONFIGURE
07-12 12:06:27.599 1002 14225 14254 I bluetooth: system/stack/rfcomm/rfc_mx_fsm.cc:70 rfc_mx_sm_execute: RFCOMM peer:xx:xx:xx:xx:51:6e event:RFC_MX_EVENT_SABME state:RFC_MX_STATE_WAIT_SABME
07-12 12:06:27.599 1002 14225 14254 I bluetooth: system/stack/rfcomm/rfc_mx_fsm.cc:70 rfc_mx_sm_execute: RFCOMM peer:xx:xx:xx:xx:51:6e event:RFC_MX_EVENT_START_RSP state:RFC_MX_STATE_WAIT_SABME
07-12 12:06:27.602 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.613 10199 13945 13945 I MediaRouter: Clearing the bluetooth route because it is no longer selectable: MediaRouter.RouteInfo{ uniqueId=android/androidx.mediarouter.media.SystemMediaRouteProvider:ROUTE_d2b052fa, name=CAR HF UNIT, description=Bluetooth audio, iconUri=null, enabled=true, connectionState=0, canDisconnect=false, playbackType=0, playbackStream=3, deviceType=3, volumeHandling=1, volume=15, volumeMax=25, presentationDisplayId=-1, extras=null, settingsIntent=null, providerPackageName=android }
07-12 12:06:27.615 10051 15188 15188 E com.android.documentsui: No package ID ff found for resource ID 0xffffffff.
07-12 12:06:27.622 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.624 1041 959 1686 E AHal::TinyalsaDriverOperations: OpenAndPrepareLocked: pcm_prepare failed, error msg: cannot prepare channel: Invalid argument
07-12 12:06:27.629 10051 15188 15188 E com.android.documentsui: No package ID ff found for resource ID 0xffffffff.
07-12 12:06:27.649 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.669 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.670 1041 959 1686 E AHal::TinyalsaDriverOperations: OpenAndPrepareLocked: pcm_prepare failed, error msg: cannot prepare channel: Invalid argument
07-12 12:06:27.695 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.708 1002 14225 14254 I bluetooth: system/stack/rfcomm/rfc_port_fsm.cc:81 rfc_port_sm_execute: bd_addr:xx:xx:xx:xx:51:6e, handle:5, state:RFC_STATE_CLOSED, event:RFC_PORT_EVENT_SABME
07-12 12:06:27.712 1002 14225 14254 E bt_btu_hcif: system/stack/btu/btu_hcif.cc:986 btu_hcif_hdl_command_complete: Command complete for opcode:0x40b should not be handled here
07-12 12:06:27.716 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.731 1002 14225 14254 I bluetooth: system/stack/rfcomm/rfc_port_fsm.cc:81 rfc_port_sm_execute: bd_addr:xx:xx:xx:xx:51:6e, handle:5, state:RFC_STATE_TERM_WAIT_SEC_CHECK, event:RFC_PORT_EVENT_SEC_COMPLETE
07-12 12:06:27.731 1002 14225 14254 I bluetooth: system/stack/rfcomm/rfc_port_fsm.cc:81 rfc_port_sm_execute: bd_addr:xx:xx:xx:xx:51:6e, handle:5, state:RFC_STATE_TERM_WAIT_SEC_CHECK, event:RFC_PORT_EVENT_ESTABLISH_RSP
07-12 12:06:27.731 1002 14225 14265 E BluetoothRemoteDevices: encryptionChangeCallback: device is NULL, address=XX:XX:XX:XX:00:00
07-12 12:06:27.732 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_act.cc:610 bta_ag_rfc_acp_open: dev_addr=xx:xx:xx:xx:51:6e, peer_addr=00:00:00:00:00:00, in_use=true, index=2
07-12 12:06:27.732 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_act.cc:610 bta_ag_rfc_acp_open: dev_addr=xx:xx:xx:xx:51:6e, peer_addr=00:00:00:00:00:00, in_use=true, index=2
07-12 12:06:27.732 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_act.cc:610 bta_ag_rfc_acp_open: dev_addr=xx:xx:xx:xx:51:6e, peer_addr=00:00:00:00:00:00, in_use=true, index=2
07-12 12:06:27.732 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_act.cc:610 bta_ag_rfc_acp_open: dev_addr=xx:xx:xx:xx:51:6e, peer_addr=00:00:00:00:00:00, in_use=true, index=2
07-12 12:06:27.732 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_act.cc:610 bta_ag_rfc_acp_open: dev_addr=xx:xx:xx:xx:51:6e, peer_addr=00:00:00:00:00:00, in_use=true, index=2
07-12 12:06:27.732 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_act.cc:610 bta_ag_rfc_acp_open: dev_addr=xx:xx:xx:xx:51:6e, peer_addr=00:00:00:00:00:00, in_use=true, index=2
07-12 12:06:27.733 1002 14225 14254 I bluetooth: system/bta/dm/bta_dm_pm.cc:518 bta_dm_pm_cback: New connection service:BTA_ID_AG[5] app_id:1
07-12 12:06:27.733 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_sco.cc:1292 bta_ag_sco_listen: xx:xx:xx:xx:51:6e
07-12 12:06:27.733 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_sco.cc:756 bta_ag_sco_event: device:xx:xx:xx:xx:51:6e index:0xffff state:BTA_AG_SCO_SHUTDOWN_ST[0] event:BTA_AG_SCO_LISTEN_E[0]
07-12 12:06:27.733 1002 14225 14254 W bluetooth: system/bta/ag/bta_ag_sco.cc:461 bta_ag_create_sco: device xx:xx:xx:xx:51:6e is not active, active_device=00:00:00:00:00:00
07-12 12:06:27.733 1002 14225 14254 W bluetooth: system/bta/ag/bta_ag_sco.cc:1244 bta_ag_sco_event: SCO_state_change: [BTA_AG_SCO_SHUTDOWN_ST(0x00)]->[BTA_AG_SCO_LISTEN_ST(0x01)] after event [BTA_AG_SCO_LISTEN_E(0x00)]
07-12 12:06:27.741 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.761 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.786 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.807 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.832 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.843 10040 15269 15269 E cutils-trace: Error opening trace file: No such file or directory (2)
07-12 12:06:27.853 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.878 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.900 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.925 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.939 1002 14225 14265 E BluetoothRemoteDevices: encryptionChangeCallback: device is NULL, address=XX:XX:XX:XX:00:00
07-12 12:06:27.946 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.971 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:27.992 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.017 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.039 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.064 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.085 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.110 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.121 1002 14225 14259 I bluetooth: system/gd/hci/hci_layer.cc:164 drop: Dropping event MAX_SLOTS_CHANGE(0x1b)
07-12 12:06:28.131 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.156 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.176 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.184 1002 14225 14265 E BluetoothRemoteDevices: encryptionChangeCallback: device is NULL, address=XX:XX:XX:XX:00:00
07-12 12:06:28.201 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.222 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.230 10040 15269 15269 E android.process.media: Unable to find pattern file or unable to map it for am
07-12 12:06:28.247 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.268 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.294 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.314 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.340 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.361 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.385 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.406 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.431 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.451 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.476 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.497 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.522 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.543 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.568 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.589 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.614 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.626 10051 15188 15188 E com.android.documentsui: No package ID ff found for resource ID 0xffffffff.
07-12 12:06:28.634 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.643 10051 15188 15188 E com.android.documentsui: No package ID ff found for resource ID 0xffffffff.
07-12 12:06:28.659 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.680 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.705 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.727 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.752 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.774 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.799 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.822 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.847 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.867 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.876 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_sco.cc:1675 bta_ag_api_set_active_device: active_device_addr00:00:00:00:00:00, new_active_device:xx:xx:xx:xx:51:6e
07-12 12:06:28.892 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.901 1002 14225 14288 E HeadsetSystemInterface: Handsfree phone proxy null for query phone state
07-12 12:06:28.908 1000 1389 2323 I Telecom : CallAudioRouteController: bluetooth route added: AudioRoute[Type=TYPE_BLUETOOTH_SCO, Address=E0:75:0A:7F:51:6E]: BSR.oR->CARC.pM_BT_DEVICE_ADDED@rQs
07-12 12:06:28.913 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.938 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.959 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:28.984 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.004 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.032 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.053 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.079 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.100 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.125 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.146 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.171 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.192 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.217 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.241 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.267 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.288 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.312 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.333 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.358 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.382 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.410 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.438 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:29.463 1041 1040 1687 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:30.146 1002 14225 14288 E HeadsetSystemInterface: getNetworkOperator() failed: mBluetoothInCallService is null
07-12 12:06:30.490 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:30.501 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:30.516 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:30.540 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:30.556 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:30.801 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:30.815 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:30.823 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:30.835 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:30.858 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:31.169 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:31.498 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:31.766 1002 14225 14288 E HeadsetSystemInterface: getNetworkOperator() failed: mBluetoothInCallService is null
07-12 12:06:33.919 1002 14225 14254 I bluetooth: system/bta/ag/bta_ag_sco.cc:1675 bta_ag_api_set_active_device: active_device_addrxx:xx:xx:xx:51:6e, new_active_device:xx:xx:xx:xx:51:6e
07-12 12:06:33.940 1000 1389 2323 I Telecom : CallAudioRouteController: request to route to bluetooth route: AudioRoute[Type=TYPE_BLUETOOTH_SCO, Address=E0:75:0A:7F:51:6E] (active=false): BSR.oR->CARC.pM_BT_ACTIVE_DEVICE_PRESENT@rQw
07-12 12:06:33.957 1041 959 990 I AHal::BT: ConnectDevice: bluetooth-sco-headset done
07-12 12:06:33.958 1041 959 990 I ASvc::Module: connectExternalDevice: new port = AudioPort{id: 300163, name: bluetooth-sco-headset, profiles: [AudioProfile{name: BT profile, format: AudioFormatDescription{type: PCM, pcm: DEFAULT, encoding: }, channelMasks: [AudioChannelLayout{layoutMask: 3}], sampleRates: [48000], encapsulationType: NONE}], flags: AudioIoFlags{output: 0}, extraAudioDescriptors: [], gains: [], ext: AudioPortExt{device: AudioPortDeviceExt{device: AudioDevice{type: AudioDeviceDescription{type: OUT_HEADSET, connection: bt-sco}, address: AudioDeviceAddress{mac: [224, 117, 10, 127, 81, 110]}}, flags: 0, encodedFormats: [], encapsulationModes: 0, encapsulationMetadataTypes: 0, speakerLayout: (null)}}}
07-12 12:06:33.968 1041 959 959 I AHal::PortRouteManager: AddPortConfiguration: add config 401189 on bluetooth-sco-headset/300163
07-12 12:06:33.971 1041 959 959 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 837, AudioPatch{id: 837, source: [401190/7: spatializer-offload-playback], sink: [401189/300163: bluetooth-sco-headset], min frames: 480, latencies: [130]}
07-12 12:06:33.989 1041 959 5788 E AHAL_DownmixContext: validateCommonConfig: output should be stereo, not AudioChannelLayout{layoutMask: 63}
07-12 12:06:33.989 1041 959 5788 E AHAL_EffectImpl: open: line92 return with expr !mImplContext
07-12 12:06:33.989 1041 1040 15315 E EffectConversionHelperAidl: Function: handleSetConfig Line: 192 Failed
07-12 12:06:33.990 1041 959 5788 E AHAL_DownmixContext: validateCommonConfig: output should be stereo, not AudioChannelLayout{layoutMask: 63}
07-12 12:06:33.990 1041 959 5788 E AHAL_EffectImpl: open: line92 return with expr !mImplContext
07-12 12:06:33.990 1041 1040 15315 E EffectConversionHelperAidl: Function: handleSetConfig Line: 192 Failed
07-12 12:06:33.995 1041 1040 15315 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:34.093 1041 959 5788 I AHal::PortRouteManager: UpdateOrAddPatch: update patch 837, AudioPatch{id: 837, source: [401190/7: spatializer-offload-playback], sink: [401189/300163: bluetooth-sco-headset], min frames: 480, latencies: [130]}
07-12 12:06:34.107 1041 959 959 I AHal::PortRouteManager: UpdatePortConfiguration: update config 401189 on bluetooth-sco-headset/300163
07-12 12:06:34.109 1041 959 959 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 838, AudioPatch{id: 838, source: [401191/3: compress-offload-playback], sink: [401189/300163: bluetooth-sco-headset], min frames: 1024, latencies: [140]}
07-12 12:06:34.226 1041 959 959 I AHal::PortRouteManager: UpdateOrAddPatch: update patch 838, AudioPatch{id: 838, source: [401191/3: compress-offload-playback], sink: [401189/300163: bluetooth-sco-headset], min frames: 1024, latencies: [140]}
07-12 12:06:34.265 1041 959 5788 E AHAL_EffectImpl: command: line271 return with expr mState == State::INIT
07-12 12:06:34.267 1041 959 959 E AHAL_EffectImpl: command: line271 return with expr mState == State::INIT
07-12 12:06:34.303 1041 959 990 I AHal::BT: ConnectDevice: bluetooth-sco-headset-microphones done
07-12 12:06:34.304 1041 959 990 I ASvc::Module: connectExternalDevice: new port = AudioPort{id: 300164, name: bluetooth-sco-headset-microphones, profiles: [AudioProfile{name: BT profile, format: AudioFormatDescription{type: PCM, pcm: DEFAULT, encoding: }, channelMasks: [AudioChannelLayout{layoutMask: 3}], sampleRates: [48000], encapsulationType: NONE}], flags: AudioIoFlags{input: 0}, extraAudioDescriptors: [], gains: [], ext: AudioPortExt{device: AudioPortDeviceExt{device: AudioDevice{type: AudioDeviceDescription{type: IN_HEADSET, connection: bt-sco}, address: AudioDeviceAddress{mac: [224, 117, 10, 127, 81, 110]}}, flags: 0, encodedFormats: [], encapsulationModes: 0, encapsulationMetadataTypes: 0, speakerLayout: (null)}}}
07-12 12:06:34.308 1041 959 990 I AHal::PortRouteManager: AddPortConfiguration: add config 401192 on bluetooth-sco-headset-microphones/300164
07-12 12:06:34.309 1041 959 990 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 839, AudioPatch{id: 839, source: [401192/300164: bluetooth-sco-headset-microphones], sink: [401193/1008: hotword-lookback-capture], min frames: 1024, latencies: [64]}
07-12 12:06:34.409 1041 959 5788 I AHal::PortRouteManager: AddPortConfiguration: add config 401194 on bluetooth-sco-headset-microphones/300164
07-12 12:06:34.411 1041 959 5788 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 840, AudioPatch{id: 840, source: [401194/300164: bluetooth-sco-headset-microphones], sink: [401195/1002: voip-capture], min frames: 960, latencies: [40]}
07-12 12:06:34.434 1041 959 5788 I AHal::PortRouteManager: AddPortConfiguration: add config 401196 on bluetooth-sco-headset-microphones/300164
07-12 12:06:34.435 1041 959 5788 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 841, AudioPatch{id: 841, source: [401196/300164: bluetooth-sco-headset-microphones], sink: [401197/1001: primary-capture], min frames: 960, latencies: [80]}
07-12 12:06:34.454 1041 959 990 I AHal::PortRouteManager: AddPortConfiguration: add config 401198 on bluetooth-sco-headset-microphones/300164
07-12 12:06:34.456 1041 959 990 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 842, AudioPatch{id: 842, source: [401198/300164: bluetooth-sco-headset-microphones], sink: [401199/1003: fast-capture], min frames: 480, latencies: [480]}
07-12 12:06:34.479 1041 959 5788 I AHal::PortRouteManager: AddPortConfiguration: add config 401200 on bluetooth-sco-headset-microphones/300164
07-12 12:06:34.480 1041 959 5788 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 843, AudioPatch{id: 843, source: [401200/300164: bluetooth-sco-headset-microphones], sink: [401201/1004: sound-trigger-capture], min frames: 1024, latencies: [64]}
07-12 12:06:34.507 1041 959 990 I AHal::PortRouteManager: AddPortConfiguration: add config 401202 on bluetooth-sco-headset-microphones/300164
07-12 12:06:34.508 1041 959 990 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 844, AudioPatch{id: 844, source: [401202/300164: bluetooth-sco-headset-microphones], sink: [401203/1007: sound-trigger-tap-capture], min frames: 1024, latencies: [256]}
07-12 12:06:34.537 1000 1389 2073 E AS.SpatializerHelper: Called setHeadTrackerEnabled enabled:false device:AudioDeviceAttributes: role:output type:bt_a2dp addr:XX:XX:XX:XX:51:6E name: profiles:[] descriptors:[] on a device without headtracker
07-12 12:06:34.909 1002 14225 14225 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:364 connectA2dpNative: xx:xx:xx:xx:51:6e
07-12 12:06:34.909 1002 14225 14225 I bluetooth-a2dp: system/btif/src/btif_av.cc:3519 btif_av_source_connect: peer=xx:xx:xx:xx:51:6e
07-12 12:06:34.910 1002 14225 14265 I bluetooth-a2dp: system/btif/src/btif_av.cc:3445 connect_int: peer=xx:xx:xx:xx:51:6e uuid=0x110a
07-12 12:06:34.911 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1256 FindOrCreatePeer: Create peer: peer=xx:xx:xx:xx:51:6e bta_handle=0x41 peer_id=0
07-12 12:06:34.911 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1650 OnEnter: state=Idle peer=xx:xx:xx:xx:51:6e
07-12 12:06:34.912 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:34.912 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:34.912 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:3885 btif_av_get_peer_sep: No active peer found
07-12 12:06:34.912 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1686 ProcessEvent: state=Idle peer=xx:xx:xx:xx:51:6e event=BTIF_AV_CONNECT_REQ_EVT(0x1a) flags=0x0(None) active_peer=false
07-12 12:06:34.912 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1284 AllowedToConnect: connected=0, max_connected_peers_=5, sink_connected_peers_size=0
07-12 12:06:34.912 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_api.cc:163 BTA_AvOpen: peer xx:xx:xx:xx:51:6e bta_handle:0x41 use_rc=true uuid=0x110a
07-12 12:06:34.912 1002 14225 14265 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:177 bta2dp_mandatory_codec_preferred_callback: xx:xx:xx:xx:51:6e
07-12 12:06:34.913 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1682 OnExit: state=Idle peer=xx:xx:xx:xx:51:6e
07-12 12:06:34.913 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1959 OnEnter: state=Opening peer=xx:xx:xx:xx:51:6e
07-12 12:06:34.913 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2869 btif_report_connection_state: peer=xx:xx:xx:xx:51:6e state=1
07-12 12:06:34.913 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_ssm.cc:442 bta_av_ssm_execute: peer 00:00:00:00:00:00 p_scb=0x41(0xb00cf7001b51b00) AV event=0x1209(API_OPEN) state=0(INIT) -> 2(OPENING)
07-12 12:06:34.913 1002 14225 14254 W bluetooth-a2dp: system/bta/av/bta_av_main.cc:1079 bta_av_link_role_ok: Unable to find link role for device:00:00:00:00:00:00
07-12 12:06:34.913 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_aact.cc:240 bta_av_save_addr: reset flags old_addr=00:00:00:00:00:00 new_addr=xx:xx:xx:xx:51:6e
07-12 12:06:34.913 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_main.cc:373 SetAvdtpVersion: AVDTP version for xx:xx:xx:xx:51:6e set to 0x102
07-12 12:06:34.913 1002 14225 14265 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:67 bta2dp_connection_state_callback: xx:xx:xx:xx:51:6e: state: BTAV_CONNECTION_STATE_CONNECTING(0x1)
07-12 12:06:34.915 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_api.cc:333 A2DP_FindService: A2DP service discovery for peer xx:xx:xx:xx:51:6e UUID 0x110b: SDP search started
07-12 12:06:34.933 1002 14225 14225 E A2dpStateMachine: Incorrect event: 1
07-12 12:06:35.054 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_api.cc:85 a2dp_sdp_cback: status: 0
07-12 12:06:35.054 1002 14225 14254 E bluetooth-a2dp: system/stack/a2dp/a2dp_api.cc:108 a2dp_sdp_cback: ATTR_ID_SERVICE_NAME attr not found!!
07-12 12:06:35.054 1002 14225 14254 E bluetooth-a2dp: system/stack/a2dp/a2dp_api.cc:121 a2dp_sdp_cback: ATTR_ID_PROVIDER_NAME attr not found!!
07-12 12:06:35.054 1002 14225 14254 E bluetooth-a2dp: system/stack/a2dp/a2dp_api.cc:134 a2dp_sdp_cback: ATTR_ID_SUPPORTED_FEATURES attr not found!!
07-12 12:06:35.054 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_main.cc:373 SetAvdtpVersion: AVDTP version for xx:xx:xx:xx:51:6e set to 0x102
07-12 12:06:35.054 1002 14225 14254 I bluetooth-a2dp: system/stack/avdt/avdt_api.cc:885 AVDT_ConnectReq: bd_addr=xx:xx:xx:xx:51:6e channel_index=0
07-12 12:06:35.055 1002 14225 14254 I bluetooth-a2dp: system/stack/avdt/avdt_api.cc:908 AVDT_ConnectReq: completed; bd_addr=xx:xx:xx:xx:51:6e result=0
07-12 12:06:35.231 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_main.cc:401 bta_av_conn_cback: conn_cback bd_addr: xx:xx:xx:xx:51:6e, scb_index: 0
07-12 12:06:35.231 1002 14225 14254 I bluetooth-a2dp: system/stack/avdt/avdt_api.cc:261 AVDT_DiscoverReq: bd_addr=xx:xx:xx:xx:51:6e channel_index=0
07-12 12:06:35.232 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_act.cc:1560 bta_av_sig_chg: AVDT_CONNECT_IND_EVT: peer xx:xx:xx:xx:51:6e selected lcb_index 0
07-12 12:06:35.232 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_act.cc:344 bta_av_rc_create: Skipping RC creation for the old AVRCP profile
07-12 12:06:35.280 1002 14225 14254 I bluetooth-a2dp: system/stack/avdt/avdt_api.cc:367 AVDT_GetCapReq: bd_addr=xx:xx:xx:xx:51:6e channel_index=0 seid=0x1 get_all_capabilities=false
07-12 12:06:35.302 1002 14225 14259 I bluetooth: system/gd/hci/acl_manager/classic_impl.h:686 on_link_supervision_timeout_changed: UNIMPLEMENTED called
07-12 12:06:35.345 1002 14225 14254 I bluetooth-a2dp: system/btif/co/bta_av_co.cc:323 ProcessSourceGetConfig: retrieved 1 capabilities from peer xx:xx:xx:xx:51:6e
07-12 12:06:35.345 1002 14225 14254 I bluetooth-a2dp: system/btif/co/bta_av_co.cc:970 ReportSourceCodecState: peer xx:xx:xx:xx:51:6e codec_config={}
07-12 12:06:35.346 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_aact.cc:1731 bta_av_getcap_results: sep_idx=0 avdt_handle=1 bta_handle=0x41
07-12 12:06:35.346 1002 14225 14265 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:113 bta2dp_audio_config_callback: xx:xx:xx:xx:51:6e: codec: SBC, local codecs: Opus, LDAC, aptX HD, aptX, AAC, SBC, selectable codecs: SBC
07-12 12:06:35.346 1002 14225 14254 I bluetooth-a2dp: system/stack/avdt/avdt_api.cc:443 AVDT_OpenReq: bd_addr=xx:xx:xx:xx:51:6e avdt_handle=1 seid=0x1
07-12 12:06:35.353 1002 14225 14225 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:560 btif_a2dp_source_allow_low_latency_audio: allowed=false
07-12 12:06:35.354 1002 14225 14254 E BTAudioA2dpAIDL: system/audio_hal_interface/aidl/a2dp/a2dp_encoding_aidl.cc:618 set_low_latency_mode_allowed: BluetoothAudio HAL is not enabled
07-12 12:06:35.549 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_api.cc:333 A2DP_FindService: A2DP service discovery for peer xx:xx:xx:xx:51:6e UUID 0x110b: SDP search started
07-12 12:06:35.603 1002 14225 14259 I bluetooth: system/gd/hci/acl_manager/classic_impl.h:686 on_link_supervision_timeout_changed: UNIMPLEMENTED called
07-12 12:06:35.707 1002 14225 14254 W bluetooth: system/stack/avrc/avrc_api.cc:907 avrc_msg_cback: auto respond msg handle:1, control:71, cr:2, opcode:x30
07-12 12:06:35.718 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_ssm.cc:442 bta_av_ssm_execute: peer xx:xx:xx:xx:51:6e p_scb=0x41(0xb00cf7001b51b00) AV event=0x121a(STR_OPEN_OK) state=2(OPENING) -> 3(OPEN)
07-12 12:06:35.719 1002 14225 14254 I bluetooth: system/bta/dm/bta_dm_pm.cc:518 bta_dm_pm_cback: New connection service:BTA_ID_AV[18] app_id:0
07-12 12:06:35.720 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x41 event=BTA_AV_OPEN_EVT(0x2)
07-12 12:06:35.720 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1978 ProcessEvent: state=Opening peer=xx:xx:xx:xx:51:6e event=BTA_AV_OPEN_EVT(0x2) flags=0x0(None) active_peer=false
07-12 12:06:35.721 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2021 ProcessEvent: Peer xx:xx:xx:xx:51:6e : event=BTA_AV_OPEN_EVT(0x2) flags=0x0(None) status=0(SUCCESS) edr=0x1
07-12 12:06:35.721 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2869 btif_report_connection_state: peer=xx:xx:xx:xx:51:6e state=2
07-12 12:06:35.721 1002 14225 14265 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:67 bta2dp_connection_state_callback: xx:xx:xx:xx:51:6e: state: BTAV_CONNECTION_STATE_CONNECTED(0x2)
07-12 12:06:35.721 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:1974 OnExit: state=Opening peer=xx:xx:xx:xx:51:6e
07-12 12:06:35.722 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2223 OnEnter: state=Opened peer=xx:xx:xx:xx:51:6e
07-12 12:06:35.723 1002 14225 14254 E bt_btif_avrc: system/btif/src/btif_rc.cc:470 btif_rc_get_device_by_bda: device not found, returning NULL!
07-12 12:06:35.723 1002 14225 14254 E bt_btif_avrc: system/btif/src/btif_rc.cc:1292 btif_rc_check_handle_pending_play: p_dev NULL
07-12 12:06:35.726 1002 14225 14225 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:560 btif_a2dp_source_allow_low_latency_audio: allowed=false
07-12 12:06:35.726 1002 14225 14254 E BTAudioA2dpAIDL: system/audio_hal_interface/aidl/a2dp/a2dp_encoding_aidl.cc:618 set_low_latency_mode_allowed: BluetoothAudio HAL is not enabled
07-12 12:06:35.749 1002 14225 14254 I bluetooth: system/stack/avrc/avrc_api.cc:1444 AVRC_SaveControllerVersion: AVRC controller version same as cached config
07-12 12:06:35.755 1002 14225 14269 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:560 btif_a2dp_source_allow_low_latency_audio: allowed=false
07-12 12:06:35.755 1002 14225 14254 E BTAudioA2dpAIDL: system/audio_hal_interface/aidl/a2dp/a2dp_encoding_aidl.cc:618 set_low_latency_mode_allowed: BluetoothAudio HAL is not enabled
07-12 12:06:35.755 1002 14225 14269 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:424 setActiveDeviceNative: xx:xx:xx:xx:51:6e
07-12 12:06:35.755 1002 14225 14269 I bluetooth-a2dp: system/btif/src/btif_av.cc:3604 btif_av_source_set_active_device: peer=xx:xx:xx:xx:51:6e
07-12 12:06:35.755 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3488 set_active_peer_int: peer_sep=Sink peer=xx:xx:xx:xx:51:6e
07-12 12:06:35.755 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:518 SetActivePeer: peer=xx:xx:xx:xx:51:6e active_peer=00:00:00:00:00:00
07-12 12:06:35.755 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:510 btif_a2dp_source_restart_session: old_peer_address=00:00:00:00:00:00 new_peer_address=xx:xx:xx:xx:51:6e state=STATE_OFF
07-12 12:06:35.755 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:707 btif_a2dp_source_stop_audio_req: state=STATE_OFF
07-12 12:06:35.755 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:433 btif_a2dp_source_startup: state=STATE_OFF
07-12 12:06:35.756 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:466 btif_a2dp_source_start_session: peer_address=xx:xx:xx:xx:51:6e state=STATE_STARTING_UP
07-12 12:06:35.756 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:1077 btif_a2dp_source_audio_tx_flush_req: state=STATE_STARTING_UP
07-12 12:06:35.756 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:889 btif_a2dp_source_audio_tx_stop_event: streaming false state=STATE_STARTING_UP
07-12 12:06:35.756 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:451 btif_a2dp_source_startup_delayed: state=STATE_STARTING_UP
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:1061 btif_a2dp_source_audio_tx_flush_event: state=STATE_RUNNING
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:484 btif_a2dp_source_start_session_delayed: peer_address=xx:xx:xx:xx:51:6e state=STATE_RUNNING
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:649 btif_a2dp_source_setup_codec: peer_address=xx:xx:xx:xx:51:6e state=STATE_RUNNING
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: system/btif/co/bta_av_co.cc:659 SetActivePeer: peer_address=xx:xx:xx:xx:51:6e
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: system/btif/co/bta_av_co.cc:686 SetActivePeer: codec = name: SBC
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: samp_freq: 44100 (0x20)
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: ch_mode: Joint (0x01)
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: block_len: 16 (0x10)
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: num_subbands: 8 (0x04)
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: alloc_method: Loundess (0x01)
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: Bit pool Min: 2 Max: 53
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: system/btif/co/bta_av_co.cc:970 ReportSourceCodecState: peer xx:xx:xx:xx:51:6e codec_config={}
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_sbc_encoder.cc:177 a2dp_sbc_encoder_update: sample_rate=44100 bits_per_sample=16 channel_count=2
07-12 12:06:35.774 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_sbc_encoder.cc:330 a2dp_sbc_feeding_reset: PCM bytes per tick 3528
07-12 12:06:35.775 1002 14225 14254 W bluetooth-a2dp: system/stack/a2dp/a2dp_sbc_encoder.cc:689 adjust_effective_mtu: Restricting AVDTP MTU size from 883 to 663
07-12 12:06:35.775 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_sbc_encoder.cc:218 a2dp_sbc_encoder_update: MTU=663, peer_mtu=883 min_bitpool=2 max_bitpool=53
07-12 12:06:35.775 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_sbc_encoder.cc:220 a2dp_sbc_encoder_update: ChannelMode=3, NumOfSubBands=8, NumOfBlocks=16, AllocationMethod=0, BitRate=328, SamplingFreq=44100 BitPool=0
07-12 12:06:35.775 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_sbc_encoder.cc:308 a2dp_sbc_encoder_update: final bit rate 328, final bit pool 53
07-12 12:06:35.775 1002 14225 14254 W bluetooth-a2dp: system/stack/a2dp/a2dp_sbc_encoder.cc:689 adjust_effective_mtu: Restricting AVDTP MTU size from 883 to 663
07-12 12:06:35.775 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_sbc_encoder.cc:811 a2dp_sbc_get_bitrate: bit rate 328
07-12 12:06:35.776 1002 14225 14254 I bluetooth: system/audio_hal_interface/aidl/a2dp/codec_status_aidl.cc:92 sbc_offloading_capability_match: offload codec=SbcConfiguration{sampleRateHz: 44100, channelMode: JOINT_STEREO, blockLength: 16, numSubbands: 8, allocMethod: ALLOC_MD_L, bitsPerSample: 16, minBitpool: 2, maxBitpool: 53} capability=SbcCapabilities{sampleRateHz: [44100], channelMode: [MONO, JOINT_STEREO], blockLength: [4, 8, 12, 16], numSubbands: [8], allocMethod: [ALLOC_MD_L], bitsPerSample: [16], minBitpool: 2, maxBitpool: 53}
07-12 12:06:35.777 1002 14225 14265 I bluetooth-a2dp: android/app/jni/com_android_bluetooth_a2dp.cpp:113 bta2dp_audio_config_callback: xx:xx:xx:xx:51:6e: codec: SBC, local codecs: Opus, LDAC, aptX HD, aptX, AAC, SBC, selectable codecs: SBC
07-12 12:06:35.778 1002 14225 14254 E FMQ : MQDescriptor is invalid or queue size is 0.
07-12 12:06:35.783 1002 14225 14225 I bluetooth-a2dp: system/btif/src/btif_a2dp_source.cc:560 btif_a2dp_source_allow_low_latency_audio: allowed=false
07-12 12:06:35.787 1002 14225 14269 E LeAudioService: handleGroupTransitToInactive: no descriptors for group: -1 or group already inactive
07-12 12:06:35.787 1002 14225 14254 I bluetooth: system/bta/le_audio/client.cc:1580 GroupSetActive: group_id: -1
07-12 12:06:35.788 1002 14225 14265 E LeAudioService: deviceDisconnected: no descriptors for group: -1
07-12 12:06:35.805 1002 14225 14259 I bluetooth: system/gd/hci/acl_manager/classic_impl.h:686 on_link_supervision_timeout_changed: UNIMPLEMENTED called
07-12 12:06:35.828 1002 14225 14254 I bluetooth-a2dp: system/stack/a2dp/a2dp_api.cc:85 a2dp_sdp_cback: status: 0
07-12 12:06:35.828 1002 14225 14254 E bluetooth-a2dp: system/stack/a2dp/a2dp_api.cc:108 a2dp_sdp_cback: ATTR_ID_SERVICE_NAME attr not found!!
07-12 12:06:35.828 1002 14225 14254 E bluetooth-a2dp: system/stack/a2dp/a2dp_api.cc:121 a2dp_sdp_cback: ATTR_ID_PROVIDER_NAME attr not found!!
07-12 12:06:35.828 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:4153 btif_av_connect_sink_delayed: peer=xx:xx:xx:xx:51:6e handle=0x1
07-12 12:06:35.828 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_AVRCP_OPEN_EVT(0x22)
07-12 12:06:35.828 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2250 ProcessEvent: state=Opened peer=xx:xx:xx:xx:51:6e event=BTIF_AV_AVRCP_OPEN_EVT(0x22) flags=0x0(None) active_peer=true
07-12 12:06:35.828 1002 14225 14254 W bluetooth-a2dp: system/btif/src/btif_av.cc:2486 ProcessEvent: Peer xx:xx:xx:xx:51:6e : Unhandled event=BTIF_AV_AVRCP_OPEN_EVT(0x22)
07-12 12:06:35.829 1041 959 5788 I AHal::BT: ConnectDevice: bluetooth-a2dp-device done
07-12 12:06:35.829 1041 959 5788 I ASvc::Module: connectExternalDevice: new port = AudioPort{id: 300165, name: bluetooth-a2dp-device, profiles: [AudioProfile{name: BT profile, format: AudioFormatDescription{type: PCM, pcm: DEFAULT, encoding: }, channelMasks: [AudioChannelLayout{layoutMask: 3}], sampleRates: [48000], encapsulationType: NONE}], flags: AudioIoFlags{output: 0}, extraAudioDescriptors: [], gains: [], ext: AudioPortExt{device: AudioPortDeviceExt{device: AudioDevice{type: AudioDeviceDescription{type: OUT_DEVICE, connection: bt-a2dp}, address: AudioDeviceAddress{mac: [224, 117, 10, 127, 81, 110]}}, flags: 0, encodedFormats: [], encapsulationModes: 0, encapsulationMetadataTypes: 0, speakerLayout: (null)}}}
07-12 12:06:35.834 1041 959 990 D AHAL_Module: connectExternalDevice: bluetooth: device port 6 device set to AudioDevice{type: AudioDeviceDescription{type: OUT_DEVICE, connection: bt-a2dp}, address: AudioDeviceAddress{mac: [224, 117, 10, 127, 81, 110]}}
07-12 12:06:35.835 1041 959 990 E AHAL_BluetoothAudioPort: initSessionType: device=AudioDeviceDescription{type: OUT_DEVICE, connection: bt-a2dp}, session_type=A2DP_SOFTWARE_ENCODING_DATAPATH is not ready
07-12 12:06:35.869 1002 14225 14254 W bluetooth: system/stack/avrc/avrc_api.cc:907 avrc_msg_cback: auto responded msg handle:1, control:71, cr:2, opcode:x31
07-12 12:06:35.911 1041 959 990 E AHAL_BluetoothAudioPort: initSessionType: device=AudioDeviceDescription{type: OUT_DEVICE, connection: bt-a2dp}, session_type=A2DP_SOFTWARE_ENCODING_DATAPATH is not ready
07-12 12:06:35.986 1041 959 990 E AHAL_BluetoothAudioPort: initSessionType: device=AudioDeviceDescription{type: OUT_DEVICE, connection: bt-a2dp}, session_type=A2DP_SOFTWARE_ENCODING_DATAPATH is not ready
07-12 12:06:36.063 1041 959 990 E AHAL_BluetoothAudioPort: initSessionType: device=AudioDeviceDescription{type: OUT_DEVICE, connection: bt-a2dp}, session_type=A2DP_SOFTWARE_ENCODING_DATAPATH is not ready
07-12 12:06:36.138 1041 959 990 E AHAL_BluetoothAudioPort: initSessionType: device=AudioDeviceDescription{type: OUT_DEVICE, connection: bt-a2dp}, session_type=A2DP_SOFTWARE_ENCODING_DATAPATH is not ready
07-12 12:06:36.214 1041 959 990 E AHAL_ModuleBluetooth: createProxy: failed to register BT port for AudioDeviceDescription{type: OUT_DEVICE, connection: bt-a2dp}
07-12 12:06:36.215 1041 959 990 E AHAL_ModuleBluetooth: Function: populateConnectedDevicePort Line: 237 Failed
07-12 12:06:36.215 1041 959 990 E AHAL_Module: Function: connectExternalDevice Line: 723 Failed
07-12 12:06:36.215 1041 1040 1093 E Hal2AidlMapper: Function: setDevicePortConnectedState Line: 1060 Failed
07-12 12:06:36.219 1041 1040 1093 E Hal2AidlMapper: [bluetooth] findOrCreateDevicePortConfig: device port for device AudioDevice{type: AudioDeviceDescription{type: OUT_DEVICE, connection: bt-a2dp}, address: AudioDeviceAddress{mac: [224, 117, 10, 127, 81, 110]}} is not found
07-12 12:06:36.219 1041 1040 1093 E Hal2AidlMapper: Function: prepareToOpenStream Line: 822 Failed
07-12 12:06:36.219 1041 1040 1093 E DeviceHalAidl: Function: openOutputStream Line: 511 Failed
07-12 12:06:36.220 1041 1040 1093 E APM_AudioPolicyManager: openOutputWithProfileAndDevice failed to open output -19
07-12 12:06:36.223 1041 959 5788 I AHal::PortRouteManager: AddPortConfiguration: add config 401204 on bluetooth-a2dp-device/300165
07-12 12:06:36.225 1041 959 990 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 845, AudioPatch{id: 845, source: [401205/7: spatializer-offload-playback], sink: [401204/300165: bluetooth-a2dp-device], min frames: 480, latencies: [130]}
07-12 12:06:36.233 1002 14225 14391 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:36.233 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:36.234 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2250 ProcessEvent: state=Opened peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=true
07-12 12:06:36.234 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2451 ProcessEvent: Peer xx:xx:xx:xx:51:6e : event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) is_low_latency=false
07-12 12:06:36.234 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_api.cc:612 BTA_AvSetLatency: Set audio/video stream low latency bta_handle:65, is_low_latency:false
07-12 12:06:36.242 1041 959 959 E AHAL_DownmixContext: validateCommonConfig: output should be stereo, not AudioChannelLayout{layoutMask: 63}
07-12 12:06:36.242 1041 959 959 E AHAL_EffectImpl: open: line92 return with expr !mImplContext
07-12 12:06:36.243 1041 1040 15353 E EffectConversionHelperAidl: Function: handleSetConfig Line: 192 Failed
07-12 12:06:36.243 1041 959 990 E AHAL_DownmixContext: validateCommonConfig: output should be stereo, not AudioChannelLayout{layoutMask: 63}
07-12 12:06:36.243 1041 959 990 E AHAL_EffectImpl: open: line92 return with expr !mImplContext
07-12 12:06:36.243 1041 1040 15353 E EffectConversionHelperAidl: Function: handleSetConfig Line: 192 Failed
07-12 12:06:36.245 1041 1040 15353 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:36.338 1041 959 990 I AHal::PortRouteManager: UpdateOrAddPatch: update patch 845, AudioPatch{id: 845, source: [401205/7: spatializer-offload-playback], sink: [401204/300165: bluetooth-a2dp-device], min frames: 480, latencies: [130]}
07-12 12:06:36.340 1002 14225 14254 E avrcp : system/profile/avrcp/device.cc:479 HandleNotification: xx:xx:xx:xx:51:6e: Unknown event registered. Event ID=Unknown Event: 0x6
07-12 12:06:36.347 1002 14225 14391 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:36.349 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:36.349 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2250 ProcessEvent: state=Opened peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=true
07-12 12:06:36.349 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2451 ProcessEvent: Peer xx:xx:xx:xx:51:6e : event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) is_low_latency=false
07-12 12:06:36.349 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_api.cc:612 BTA_AvSetLatency: Set audio/video stream low latency bta_handle:65, is_low_latency:false
07-12 12:06:36.356 1041 959 959 I AHal::PortRouteManager: UpdatePortConfiguration: update config 401204 on bluetooth-a2dp-device/300165
07-12 12:06:36.357 1041 959 959 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 846, AudioPatch{id: 846, source: [401206/3: compress-offload-playback], sink: [401204/300165: bluetooth-a2dp-device], min frames: 1024, latencies: [140]}
07-12 12:06:36.366 1002 14225 14391 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:36.366 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:36.366 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2250 ProcessEvent: state=Opened peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=true
07-12 12:06:36.366 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2451 ProcessEvent: Peer xx:xx:xx:xx:51:6e : event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) is_low_latency=false
07-12 12:06:36.367 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_api.cc:612 BTA_AvSetLatency: Set audio/video stream low latency bta_handle:65, is_low_latency:false
07-12 12:06:36.471 1041 959 959 I AHal::PortRouteManager: UpdateOrAddPatch: update patch 846, AudioPatch{id: 846, source: [401206/3: compress-offload-playback], sink: [401204/300165: bluetooth-a2dp-device], min frames: 1024, latencies: [140]}
07-12 12:06:36.479 1002 14225 14391 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:36.479 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:36.479 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2250 ProcessEvent: state=Opened peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=true
07-12 12:06:36.480 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2451 ProcessEvent: Peer xx:xx:xx:xx:51:6e : event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) is_low_latency=false
07-12 12:06:36.480 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_api.cc:612 BTA_AvSetLatency: Set audio/video stream low latency bta_handle:65, is_low_latency:false
07-12 12:06:36.502 1002 14225 14364 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:36.502 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:36.502 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2250 ProcessEvent: state=Opened peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=true
07-12 12:06:36.502 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2451 ProcessEvent: Peer xx:xx:xx:xx:51:6e : event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) is_low_latency=false
07-12 12:06:36.502 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_api.cc:612 BTA_AvSetLatency: Set audio/video stream low latency bta_handle:65, is_low_latency:false
07-12 12:06:36.508 1041 959 990 E AHAL_EffectImpl: command: line271 return with expr mState == State::INIT
07-12 12:06:36.509 1041 959 5788 E AHAL_EffectImpl: command: line271 return with expr mState == State::INIT
07-12 12:06:36.516 1002 14225 14364 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:36.516 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:36.516 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2250 ProcessEvent: state=Opened peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=true
07-12 12:06:36.516 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2451 ProcessEvent: Peer xx:xx:xx:xx:51:6e : event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) is_low_latency=false
07-12 12:06:36.516 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_api.cc:612 BTA_AvSetLatency: Set audio/video stream low latency bta_handle:65, is_low_latency:false
07-12 12:06:36.533 1000 1389 1389 V MediaRouter: Audio routes updated: AudioRoutesInfo{ type=SPEAKER, bluetoothName=CAR HF UNIT }, a2dp=true
07-12 12:06:36.534 10121 2136 2136 V MediaRouter: Audio routes updated: AudioRoutesInfo{ type=SPEAKER, bluetoothName=CAR HF UNIT }, a2dp=true
07-12 12:06:36.539 10172 13983 13983 V MediaRouter: Audio routes updated: AudioRoutesInfo{ type=SPEAKER, bluetoothName=CAR HF UNIT }, a2dp=true
07-12 12:06:36.540 10199 13945 13945 V MediaRouter: Audio routes updated: AudioRoutesInfo{ type=SPEAKER, bluetoothName=CAR HF UNIT }, a2dp=true
07-12 12:06:36.544 10199 13945 13945 I MediaRouter: Found bluetooth route: MediaRouter.RouteInfo{ uniqueId=android/androidx.mediarouter.media.SystemMediaRouteProvider:ROUTE_d2b052fa, name=CAR HF UNIT, description=Bluetooth audio, iconUri=null, enabled=true, connectionState=0, canDisconnect=false, playbackType=0, playbackStream=3, deviceType=3, volumeHandling=1, volume=15, volumeMax=25, presentationDisplayId=-1, extras=null, settingsIntent=null, providerPackageName=android }
07-12 12:06:36.559 1002 14225 14225 I bluetooth: system/btif/avrcp/avrcp_service.cc:572 SendMediaUpdate: track_changed=false : play_state=true : queue=false
07-12 12:06:36.595 1041 959 10721 I AHal::PortRouteManager: AddPortConfiguration: add config 401207 on bluetooth-a2dp-device/300165
07-12 12:06:36.596 1041 959 990 I AHal::PortRouteManager: UpdateOrAddPatch: add patch 847, AudioPatch{id: 847, source: [401208/7: spatializer-offload-playback], sink: [401207/300165: bluetooth-a2dp-device], min frames: 480, latencies: [130]}
07-12 12:06:36.600 1002 14225 14337 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:36.600 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:36.601 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2250 ProcessEvent: state=Opened peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=true
07-12 12:06:36.601 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2451 ProcessEvent: Peer xx:xx:xx:xx:51:6e : event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) is_low_latency=false
07-12 12:06:36.601 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_api.cc:612 BTA_AvSetLatency: Set audio/video stream low latency bta_handle:65, is_low_latency:false
07-12 12:06:36.607 1041 959 10721 E AHAL_DownmixContext: validateCommonConfig: output should be stereo, not AudioChannelLayout{layoutMask: 63}
07-12 12:06:36.607 1041 959 10721 E AHAL_EffectImpl: open: line92 return with expr !mImplContext
07-12 12:06:36.607 1041 1040 15363 E EffectConversionHelperAidl: Function: handleSetConfig Line: 192 Failed
07-12 12:06:36.608 1041 959 10721 E AHAL_DownmixContext: validateCommonConfig: output should be stereo, not AudioChannelLayout{layoutMask: 63}
07-12 12:06:36.608 1041 959 10721 E AHAL_EffectImpl: open: line92 return with expr !mImplContext
07-12 12:06:36.608 1041 1040 15363 E EffectConversionHelperAidl: Function: handleSetConfig Line: 192 Failed
07-12 12:06:36.609 1041 1040 15363 E StreamHalAidl: Function: getPresentationPosition Line: 909 Failed
07-12 12:06:36.695 1041 959 10721 I AHal::PortRouteManager: UpdateOrAddPatch: update patch 847, AudioPatch{id: 847, source: [401208/7: spatializer-offload-playback], sink: [401207/300165: bluetooth-a2dp-device], min frames: 480, latencies: [130]}
07-12 12:06:36.700 1002 14225 14337 I bluetooth-a2dp: system/btif/src/btif_av.cc:4085 btif_av_set_low_latency: active_peer=xx:xx:xx:xx:51:6e is_low_latency=false
07-12 12:06:36.701 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:3088 btif_av_handle_event: peer=xx:xx:xx:xx:51:6e handle=0x0 event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25)
07-12 12:06:36.701 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2250 ProcessEvent: state=Opened peer=xx:xx:xx:xx:51:6e event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) active_peer=true
07-12 12:06:36.701 1002 14225 14254 I bluetooth-a2dp: system/btif/src/btif_av.cc:2451 ProcessEvent: Peer xx:xx:xx:xx:51:6e : event=BTIF_AV_SET_LATENCY_REQ_EVT(0x25) flags=0x0(None) is_low_latency=false
07-12 12:06:36.701 1002 14225 14254 I bluetooth-a2dp: system/bta/av/bta_av_api.cc:612 BTA_AvSetLatency: Set audio/video stream low latency bta_handle:65, is_low_latency:false
07-12 12:06:36.719 1041 959 990 E AHAL_DownmixContext: validateCommonConfig: output should be stereo, not AudioChannelLayout{layoutMask: 63}
07-12 12:06:36.719 1041 959 990 E AHAL_EffectImpl: open: line92 return with expr !mImplContext
07-12 12:06:36.720 1041 1040 1528 E EffectConversionHelperAidl: Function: handleSetConfig Line: 192 Failed
07-12 12:06:36.720 1041 959 990 E AHAL_DownmixContext: validateCommonConfig: output should be stereo, not AudioChannelLayout{layoutMask: 63}
07-12 12:06:36.721 1041 959 990 E AHAL_EffectImpl: open: line92 return with expr !mImplContext
07-12 12:06:36.721 1041 1040 1528 E EffectConversionHelperAidl: Function: handleSetConfig Line: 192 Failed
07-12 12:06:36.722 1041 959 990 E AHAL_EffectImpl: command: line271 return with expr mState == State::INIT
07-12 12:06:36.724 1041 959 10721 E AHAL_EffectImpl: command: line271 return with expr mState == State::INIT
07-12 12:06:37.604 10109 2398 2866 E PixelDisplayManager: setLbeState to 1
07-12 12:06:38.552 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:38.577 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:38.594 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:38.603 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:38.625 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:38.636 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:38.657 root 789 2442 E powerhal-libperfmgr: Error: shouldn't report duration during pause state.
07-12 12:06:38.732 1002 14225 14259 I bluetooth: system/gd/hci/le_scanning_manager.cc:597 stop_scan: Scanning already stopped, return!
07-12 12:06:38.733 1002 14225 14259 I bluetooth: system/gd/hci/le_address_manager.cc:274 register_client: Client registered
07-12 12:06:38.733 1002 14225 14259 I bluetooth: system/gd/hci/le_address_manager.cc:289 unregister_client: Client unregistered
07-12 12:06:38.744 1002 14225 14259 I bluetooth: system/gd/hci/le_scanning_manager.cc:597 stop_scan: Scanning already stopped, return!
07-12 12:06:38.744 1002 14225 14259 I bluetooth: system/gd/hci/le_address_manager.cc:274 register_client: Client registered
07-12 12:06:39.218 1002 14225 14254 W bluetooth-a2dp: system/bta/av/bta_av_aact.cc:2980 bta_av_open_rc: Using the new AVRCP Profile
07-12 12:06:39.218 1002 14225 14254 I bluetooth: system/btif/avrcp/avrcp_service.cc:548 ConnectDevice: address=xx:xx:xx:xx:51:6e
07-12 12:06:39.666 10109 2398 2866 E PixelDisplayManager: setLbeState to 0
07-12 12:06:41.535 10183 15379 15379 E oid.apps.photos: Not starting debugger since process cannot load the jdwp agent.
07-12 12:06:41.775 10183 15379 15379 E cutils-trace: Error opening trace file: No such file or directory (2)
07-12 12:06:41.992 1002 14225 14288 E HeadsetSystemInterface: getNetworkOperator() failed: mBluetoothInCallService is null
At arround 12:06:27 we can see that the disconnect sequence is triggered.
An error @miguel7501 and I seem to have in common is the Command complete for opcode:0x80d should not be handled here, I dont know if it's relevant.
I also have a Unhandled BQR subevent except mine is the subevent 0x03.
For testing purposes I was thinking about trying to change the A2DP hardware offloading as well as the AVRCP version available in the developer settings.
Let me know if you'd like to have more info or if you want me to try things out (I'm not willing to go through a factory reset tho).
