Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Audio playback stops working when connected to Bluetooth #28

Open
patzly opened this issue Mar 17, 2024 · 1 comment
Open

Audio playback stops working when connected to Bluetooth #28

patzly opened this issue Mar 17, 2024 · 1 comment
Labels
bug Something isn't working help wanted Extra attention is needed

Comments

@patzly
Copy link
Owner

patzly commented Mar 17, 2024 •
edited
Loading

After a random amount of seconds/minutes. Logs do not say anything important when metronome is started and doesn't play anything anymore:

2024-03-29 16:14:57.365   953-1006  IPCThreadState          ven...oogle.wifi_ext-service-vendor  E  binder thread pool (1 threads) starved for 123 ms
2024-03-29 16:14:57.419  1552-1921  ConnectivityService     system_server                        D  NetReassign [no changes] [c 1] [a 1] [i 4]
2024-03-29 16:14:57.749   955-2958  AudioFlinger            audioserver                          D  Client defaulted notificationFrames to 1924 for frameCount 3848
2024-03-29 16:14:57.758   881-881   EffectsFactory          android.hardware.audio.service       I  EffectCreate() create_effect
2024-03-29 16:14:57.767  1999-1999  AlarmManager            com.android.systemui                 W  Unrecognized alarm listener com.google.android.systemui.ambientmusic.AmbientIndicationService$$ExternalSyntheticLambda0@8cf53d1
2024-03-29 16:14:57.767  1999-1999  AmbientIndication       com.android.systemui                 I  Hiding ambient indication.
2024-03-29 16:14:57.768 21440-6578  NearbyDiscovery         com.google.android.gms.persistent    I  (REDACTED) ActiveMediaSession: Created, %s
2024-03-29 16:14:57.780  5250-5333  bt                      com.google.android.bluetooth         I  bluetooth_core::gatt::server::isolation_manager: removing server (if any) associated with advertiser AdvertiserId(0)
2024-03-29 16:14:57.780  5250-5376  bt_stack                com.google.android.bluetooth         I  [INFO:le_advertising_manager.cc(76)] Unregister in shim layer
2024-03-29 16:14:57.781 21440-6578  NearbyMediums           com.google.android.gms.persistent    I  [InstantOnLostManager] Stop on lost advertising for hash : [ 0xee 0xb9 0x1e 0x24 ]
2024-03-29 16:14:57.782   883-5301  bthal.activity          and....bluetooth-service.bcmbtlinux  I  bt_power: LeAdvStat: 16:14:56:781 - 16:14:57:782, duration: 1001, le_adv_count: 2, total_le_adv_count: 416, total_event_count: 26
2024-03-29 16:14:57.793  5250-5289  bluetooth               com.google.android.bluetooth         E  packages/modules/Bluetooth/system/gd/hci/event_checkers.h:37 - check_complete: Error code COMMAND_DISALLOWED, opcode 0x2040
2024-03-29 16:14:57.806   881-881   audio_hw_patch          android.hardware.audio.service       D  low-latency-playback routing:
2024-03-29 16:14:57.806   881-881   audio_hw_patch          android.hardware.audio.service       D  dev: 0x80 
2024-03-29 16:14:57.806   881-881   audio_hw_bt             android.hardware.audio.service       D  get_recommended_latency_modes: 4
2024-03-29 16:14:57.806   881-881   audio_hw_a2dp           android.hardware.audio.service       D  a2dp_wrapper_get_supported_latency_modes: get 1 A2DP latency modes
2024-03-29 16:14:57.806   881-881   audio_hw_bt             android.hardware.audio.service       D  get_a2dp_recommended_latency_modes: get 1 latency modes
2024-03-29 16:14:57.811   955-2958  audioserver             audioserver                          D  logFgsApiBegin: FGS Logger Transaction failed, -129
2024-03-29 16:14:57.815   881-881   audio_hw_soundtrigger   android.hardware.audio.service       D  updated Stream 3 active in soundtrigger
2024-03-29 16:14:57.815   881-881   audio_hw_output_stream  android.hardware.audio.service       D  update stream 3 active 1 gain 0.000000
2024-03-29 16:14:57.816   881-12762 audio_hw                android.hardware.audio.service       D  prepare low-latency-playback
2024-03-29 16:14:57.816   881-12762 audio_hw_soundtrigger   android.hardware.audio.service       D  st_uc_monitor:uc:low-latency-playback active
2024-03-29 16:14:57.816   881-12762 audio_hw                android.hardware.audio.service       D  low-latency-playback:
2024-03-29 16:14:57.816   881-12762 audio_hw                android.hardware.audio.service       D  rx:
2024-03-29 16:14:57.816   881-12762 audio_hw                android.hardware.audio.service       D    #0: OUT_A2DP_BE_CFG 3
2024-03-29 16:14:57.816   881-12762 audio_hw                android.hardware.audio.service       D  select bit 16 rate 48000 ch 1 for intf I2S_RX_0
2024-03-29 16:14:57.816   881-12762 audio_hw_bt             android.hardware.audio.service       I  require_reinit_a2dp: playback PLAYBACK_STANDBY (1) needs to resume
2024-03-29 16:14:57.817  1150-1150  AOC                     aocd                                 D  F1:MSG: controller.cc, 273: AudioOutCtrl: cmd ID: 0x00f2, tag: 0x24 [cntr = 6908]
2024-03-29 16:14:57.817  1552-2345  MediaFocusControl       system_server                        I  requestAudioFocus() from uid/pid 10442/5182 AA=USAGE_MEDIA/CONTENT_TYPE_MUSIC clientId=android.media.AudioManager@d1e6d83xyz.zedler.patrick.tack.util.AudioUtil@1a4bb00 callingPack=xyz.zedler.patrick.tack req=1 flags=0x2 sdk=34
2024-03-29 16:14:57.817  1150-1150  AOC                     aocd                                 D  F1:SinkBT: Received Encoder Configuration:
2024-03-29 16:14:57.817  1150-1150  AOC                     aocd                                 D  F1:   Bitrate: 323000
2024-03-29 16:14:57.817  1150-1150  AOC                     aocd                                 D  F1:  Peer MTU: 660
2024-03-29 16:14:57.817  1150-1150  AOC                     aocd                                 D  F1:Loading Library 11 at 0x404b2bc0 (size 9216)
2024-03-29 16:14:57.817  1150-1150  AOC                     aocd                                 D  F1:MSG: memory_overlay_xtensa.cc, 51: Custom overlay loader
2024-03-29 16:14:57.817  1150-1150  AOC                     aocd                                 D  F1:  Codec Type: SBC (1)
2024-03-29 16:14:57.818  1150-1150  AOC                     aocd                                 D  F1:  Codec Configuration:
2024-03-29 16:14:57.818  1552-2345  AS.FadeOutManager       system_server                        I  unfadeOutUid() uid:10442
2024-03-29 16:14:57.818  1150-1150  AOC                     aocd                                 D  F1:    Sampling Frequency: 2
2024-03-29 16:14:57.818  1150-1150  AOC                     aocd                                 D  F1:          Channel mode: 1
2024-03-29 16:14:57.818  1150-1150  AOC                     aocd                                 D  F1:          Block length: 1
2024-03-29 16:14:57.818  1150-1150  AOC                     aocd                                 D  F1:    Number of subbands: 1
2024-03-29 16:14:57.818  1150-1150  AOC                     aocd                                 D  F1:     Allocation Method: 1
2024-03-29 16:14:57.818  1150-1150  AOC                     aocd                                 D  F1:       Minimum bitpool: 2
2024-03-29 16:14:57.819  1150-1150  AOC                     aocd                                 D  F1:       Maximum bitpool: 52
2024-03-29 16:14:57.819   881-12762 audio_hw_a2dp           android.hardware.audio.service       I  a2dp_wrapper_start_stream: 0x200 mode 0
2024-03-29 16:14:57.819  1150-1150  AOC                     aocd                                 D  F1:      SBC frame length: 117
2024-03-29 16:14:57.819  1150-1150  AOC                     aocd                                 D  F1:         Final bitpool: 52
2024-03-29 16:14:57.819  1150-1150  AOC                     aocd                                 D  F1:         Final bitrate: 322481
2024-03-29 16:14:57.819  1150-1150  AOC                     aocd                                 D  F1:MSG: controller.cc, 811: AudioOutCtrl: ipc: audio_output_co, cmd ID: 0x00f2, tag: 0x24, rc: 0
2024-03-29 16:14:57.819  5250-5376  btif_av                 com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_av.cc:3905 - btif_av_stream_started_ready: btif_av_stream_started_ready: Peer xx:xx:xx:xx:c6:30 : state=2 flags=0x0(None) ready=0
2024-03-29 16:14:57.819  5250-5376  btif_av                 com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_av.cc:3876 - btif_av_stream_ready: btif_av_stream_ready: Peer xx:xx:xx:xx:c6:30 : state=2, flags=0x0(None)
2024-03-29 16:14:57.819  5250-5376  btif_av                 com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_av.cc:3790 - btif_av_stream_start_with_latency: btif_av_stream_start_with_latency
2024-03-29 16:14:57.819  5250-5376  btif_av                 com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_av.cc:3799 - btif_av_stream_start_with_latency: peer_address=xx:xx:xx:xx:c6:30 event=BTIF_AV_START_STREAM_REQ_EVT(0x1c) use_latency_mode=false
2024-03-29 16:14:57.820  5250-5304  BluetoothServiceJni     com.google.android.bluetooth         V  switch_codec_callback: SwitchCodecCallback: false
2024-03-29 16:14:57.820  5250-5376  bt_stack                com.google.android.bluetooth         I  [INFO:a2dp_encoding_aidl.cc(103)] StartRequest: accepted
2024-03-29 16:14:57.820  5250-5284  btif_av                 com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_av.cc:2338 - ProcessEvent: virtual bool BtifAvStateMachine::StateOpened::ProcessEvent(uint32_t, void *): Peer xx:xx:xx:xx:c6:30 : event=BTIF_AV_START_STREAM_REQ_EVT(0x1c) flags=0x0(None)
2024-03-29 16:14:57.820  5250-5284  btif_av                 com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_av.cc:2343 - ProcessEvent: Stream use_latency_mode=false
2024-03-29 16:14:57.820  5250-5284  bt_bta_av               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/bta/av/bta_av_api.cc:236 - BTA_AvStart: Starting audio/video stream data transfer bta_handle:65, use_latency_mode:false
2024-03-29 16:14:57.820  5250-5284  bt_bta_av               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/bta/av/bta_av_aact.cc:1892 - bta_av_do_start: A2dp stream start peer:xx:xx:xx:xx:c6:30 sco_occupied:false av_role:0x0 started:false wait:0x0
2024-03-29 16:14:57.820  5250-5304  BluetoothAdapterService com.google.android.bluetooth         I  getActiveDevices: A2dp device: XX:XX:XX:XX:C6:30
2024-03-29 16:14:57.820  5250-5284  bt_btm_pm               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/stack/acl/btm_pm.cc:269 - BTM_SetPowerMode: Setting power mode for peer:xx:xx:xx:xx:c6:30 current_mode:immediate:sniff[2] new_mode:forced:active[0]
2024-03-29 16:14:57.820  5250-5284  bt_btm_pm               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/stack/acl/btm_pm.cc:570 - btm_pm_snd_md_req: Switching from immediate:sniff[0x02] to immediate:active[0x00]
2024-03-29 16:14:57.820  1999-1999  AlarmManager            com.android.systemui                 W  Unrecognized alarm listener com.google.android.systemui.ambientmusic.AmbientIndicationService$$ExternalSyntheticLambda0@8cf53d1
2024-03-29 16:14:57.820  1999-1999  AmbientIndication       com.android.systemui                 I  Hiding ambient indication.
2024-03-29 16:14:57.820  5250-5284  bt_l2cap                com.google.android.bluetooth         I  packages/modules/Bluetooth/system/stack/l2cap/l2c_api.cc:1012 - L2CA_UseLatencyMode: BDA: xx:xx:xx:xx:c6:30, use_latency_mode: false
2024-03-29 16:14:57.820  5250-5284  bt_bta_av               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/bta/av/bta_av_aact.cc:1958 - bta_av_do_start: bta_av_do_start: peer xx:xx:xx:xx:c6:30 start requested: sco_occupied:false role:0x10 started:false wait:0x0
2024-03-29 16:14:57.820 21440-6578  NearbyDiscovery         com.google.android.gms.persistent    I  (REDACTED) ActiveMediaSessionManager: Find a trigger event! %s
2024-03-29 16:14:57.820 21440-6578  NearbyDiscovery         com.google.android.gms.persistent    I  ConnectionSwitchManager: enableOnSightRunnable no need to enable onSight callback because not an outgoing call [CONTEXT service_id=265 ]
2024-03-29 16:14:57.821 21440-6578  NearbyFastPair          com.google.android.gms.persistent    I  (REDACTED) NearbyDeviceManager: setOnSightCallback to %s
2024-03-29 16:14:57.821 21440-6578  NearbyDiscovery         com.google.android.gms.persistent    I  (REDACTED) FastPair: ConnectionSwitchManager receiveAudioEvent=%s and try to switch
2024-03-29 16:14:57.824  5250-5284  bt_btm_pm               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/stack/acl/btm_pm.cc:684 - btm_pm_proc_cmd_status: Clearing pending power mode link state:immediate:pending
2024-03-29 16:14:57.824  5250-5284  bluetooth               com.google.android.bluetooth         E  packages/modules/Bluetooth/system/bta/dm/bta_dm_pm.cc:1158 - bta_dm_pm_btm_status: Received unknown power mode status event:5
2024-03-29 16:14:57.826  5250-5284  bt_btu_hcif             com.google.android.bluetooth         E  packages/modules/Bluetooth/system/stack/btu/btu_hcif.cc:1176 - btu_hcif_hdl_command_complete: Command complete for opcode:0x80d should not be handled here
2024-03-29 16:14:57.826 21440-6539  NearbyDiscovery         com.google.android.gms.persistent    I  ConnectionSwitchManager: already has bluetooth headset support related profile [CONTEXT service_id=265 ]
2024-03-29 16:14:57.826 21440-6539  NearbyDiscovery         com.google.android.gms.persistent    I  FastPair: ConnectionSwitchManager phone already has associated headset [CONTEXT service_id=265 ]
2024-03-29 16:14:57.826  5182-5182  MetronomeUtil           xyz.zedler.patrick.tack              I  start: started metronome handler
2024-03-29 16:14:57.829   973-973   Vibrator                and...ware.vibrator-service.cs40l25  D  perform
2024-03-29 16:14:57.829   973-973   Vibrator                and...ware.vibrator-service.cs40l25  D  on
2024-03-29 16:14:57.830   973-6598  Vibrator                and...ware.vibrator-service.cs40l25  D  waitForComplete
2024-03-29 16:14:57.860   973-6598  Vibrator                and...ware.vibrator-service.cs40l25  D  waitForComplete: Get STOP! Set active to 0.
2024-03-29 16:14:57.860   973-973   Vibrator                and...ware.vibrator-service.cs40l25  D  off
2024-03-29 16:14:57.865  5250-5284  bt_btm_pm               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/stack/acl/btm_pm.cc:724 - btm_pm_proc_mode_change: Power mode switched from immediate:pending[5] to immediate:active[0]
2024-03-29 16:14:57.879  1999-1999  AlarmManager            com.android.systemui                 W  Unrecognized alarm listener com.google.android.systemui.ambientmusic.AmbientIndicationService$$ExternalSyntheticLambda0@8cf53d1
2024-03-29 16:14:57.879  1999-1999  AmbientIndication       com.android.systemui                 I  Hiding ambient indication.
2024-03-29 16:14:57.971 21440-6539  NearbyMediums           com.google.android.gms.persistent    I  Found Fast Ble Advertisements :
                                                                                                    BleAdvertisement { version=2, socketVersion=2, serviceIdHash=null, data=[ 0x23 0x34 0x55 0x38 0x52 0x11 0x32 0xeb 0x1f 0x86 0xcb 0xe4 0x31 0xe1 0x0c 0x42 0xed 0xaa 0x9c 0x98 0xcb 0x09 0xbe ], deviceToken=[ 0x81 0xe7 ] } : 1 times.
                                                                                                    Total 1 fast advertisements.
2024-03-29 16:14:58.205  5250-5284  bt_bta_av               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/bta/av/bta_av_aact.cc:2288 - bta_av_start_ok: bta_av_start_ok: peer xx:xx:xx:xx:c6:30 bta_handle:0x41 wait:0x0 role:0x10 local_tsep:0
2024-03-29 16:14:58.205  5250-5284  l2c_utils               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/stack/l2cap/l2c_utils.cc:2248 - l2cu_set_acl_priority_latency_brcm: Set ACL priority: High Priority Mode
2024-03-29 16:14:58.206  5250-5284  btif_av                 com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_av.cc:2358 - ProcessEvent: virtual bool BtifAvStateMachine::StateOpened::ProcessEvent(uint32_t, void *): Peer xx:xx:xx:xx:c6:30 : event=BTA_AV_START_EVT(0x4) status=0 suspending=0 initiator=1 flags=0x4(PENDING_START)
2024-03-29 16:14:58.206  5250-5284  bt_stack                com.google.android.bluetooth         I  [INFO:btif_a2dp.cc(64)] btif_a2dp_on_started: ## ON A2DP STARTED ## peer xx:xx:xx:xx:c6:30 p_av_start:0xb40000797a9d4cc0
2024-03-29 16:14:58.206  5250-5284  bt_stack                com.google.android.bluetooth         I  [INFO:btif_a2dp.cc(85)] btif_a2dp_on_started: peer xx:xx:xx:xx:c6:30 status:0 suspending:false initiator:true
2024-03-29 16:14:58.206  5250-5284  btif_av                 com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_av.cc:3850 - btif_av_stream_start_offload: btif_av_stream_start_offload
2024-03-29 16:14:58.206  5250-5284  bt_btif_a2dp_sink       com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_a2dp_sink.cc:584 - btif_a2dp_sink_set_rx_flush: btif_a2dp_sink_set_rx_flush: enable=false
2024-03-29 16:14:58.206  5250-5284  btif_av                 com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_av.cc:2965 - btif_report_audio_state: btif_report_audio_state: peer_address=xx:xx:xx:xx:c6:30 state=2
2024-03-29 16:14:58.206  5250-5304  BluetoothA2dpServiceJni com.google.android.bluetooth         I  bta2dp_audio_state_callback
2024-03-29 16:14:58.206  5250-5284  bt_bta_av               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/bta/av/bta_av_api.cc:257 - BTA_AvOffloadStart: BTA_AvOffloadStart: bta_handle=0x41
2024-03-29 16:14:58.206  5250-5284  a2dp_sbc_encoder        com.google.android.bluetooth         I  packages/modules/Bluetooth/system/stack/a2dp/a2dp_sbc_encoder.cc:867 - a2dp_sbc_get_bitrate: a2dp_sbc_get_bitrate: bit rate 323 
2024-03-29 16:14:58.206  5250-5284  bt_bta_av               com.google.android.bluetooth         I  packages/modules/Bluetooth/system/bta/av/bta_av_aact.cc:3197 - bta_av_vendor_offload_start: codec: 0x1, sample rate: 0x1, bit depth: 0x1, channel: 0x2, bitrate: 0x4edb8, ACL: 0xb, L2CAP: 0x46, MTU: 0x294
2024-03-29 16:14:58.206  5250-5304  A2dpNativeInterface     com.google.android.bluetooth         D  onAudioStateChanged: A2dpStackEvent {type:EVENT_TYPE_AUDIO_STATE_CHANGED, device:XX:XX:XX:XX:C6:30, value1:STARTED}
2024-03-29 16:14:58.207  5250-5336  A2dpStateMachine        com.google.android.bluetooth         D  handleMessage: E msg.what=101
2024-03-29 16:14:58.207  5250-5336  A2dpStateMachine        com.google.android.bluetooth         D  processMsg: Connected
2024-03-29 16:14:58.207  5250-5336  A2dpStateMachine        com.google.android.bluetooth         D  Connected process message(XX:XX:XX:XX:C6:30): STACK_EVENT
2024-03-29 16:14:58.207  5250-5336  A2dpStateMachine        com.google.android.bluetooth         D  Connected: stack event: A2dpStackEvent {type:EVENT_TYPE_AUDIO_STATE_CHANGED, device:XX:XX:XX:XX:C6:30, value1:STARTED}
2024-03-29 16:14:58.207  5250-5336  A2dpStateMachine        com.google.android.bluetooth         I  Connected: started playing: XX:XX:XX:XX:C6:30
2024-03-29 16:14:58.207  5250-5336  A2dpStateMachine        com.google.android.bluetooth         D  A2DP Playing state : device: XX:XX:XX:XX:C6:30 State:NOT_PLAYING->PLAYING
2024-03-29 16:14:58.207   883-1101  bthal.flowcontrol       and....bluetooth-service.bcmbtlinux  I  HciEventPreProcess: HCI Event 0xff Opcode 0 Length 14 unhandled by debug_VendorEvent
2024-03-29 16:14:58.208  5250-5289  bluetooth               com.google.android.bluetooth         W  packages/modules/Bluetooth/system/gd/hci/vendor_specific_event_manager.cc:86 - on_vendor_specific_event: Unhandled vendor specific event of type 0x90
2024-03-29 16:14:58.209  5250-5336  A2dpStateMachine        com.google.android.bluetooth         D  handleMessage: X
2024-03-29 16:14:58.209  5250-5284  bt_btif_a2dp            com.google.android.bluetooth         I  packages/modules/Bluetooth/system/btif/src/btif_a2dp.cc:157 - btif_a2dp_on_offload_started: btif_a2dp_on_offload_started: peer xx:xx:xx:xx:c6:30 status 0
2024-03-29 16:14:58.210  5250-5284  bt_stack                com.google.android.bluetooth         I  [INFO:a2dp_encoding_aidl.cc(628)] ack_stream_started: result=SUCCESS_FINISHED
2024-03-29 16:14:58.210   881-894   BTAudioProviderStub     android.hardware.audio.service       I  streamStarted - SessionType=A2DP_HARDWARE_OFFLOAD_ENCODING_DATAPATH, status=SUCCESS
2024-03-29 16:14:58.210   881-894   BTAudioSessionAidl      android.hardware.audio.service       I  ReportControlStatus - status=SUCCESS for SessionType=A2DP_HARDWARE_OFFLOAD_ENCODING_DATAPATH, bluetooth_audio=0x0200 started
2024-03-29 16:14:58.210   881-894   audio_hw_a2dp           android.hardware.audio.service       I  ctrl_cb: 0x200 resume result SUCCESS
2024-03-29 16:14:58.210   881-12762 audio_hw_a2dp           android.hardware.audio.service       I  a2dp_wrapper_start_stream: 0x200 done
2024-03-29 16:14:58.210   881-12762 audio_hw_a2dp           android.hardware.audio.service       D  start: a2dp 0xb400007942957018
2024-03-29 16:14:58.210   881-12762 audio_hw_bt             android.hardware.audio.service       D  send_cmd_to_monitor_thread: sending command CMD_A2DP_RETRY_STOP (4) to monitor thread
2024-03-29 16:14:58.211   881-1456  audio_hw_bt             android.hardware.audio.service       I  cmd_to_thread_monitoring_mask: scheduling CMD_A2DP_RETRY_STOP (4)
2024-03-29 16:14:58.211   881-12762 audio_hw_bt             android.hardware.audio.service       D  configure_bt_mode: BT mode A2DP_ENC_SBC
2024-03-29 16:14:58.211   881-12762 audio_hw_soundtrigger   android.hardware.audio.service       D  st_comm_aud_event_monitor:codec dev:26 active
2024-03-29 16:14:58.211   881-12762 audio_hw_aoc            android.hardware.audio.service       I  Mode Ambient is already selected
2024-03-29 16:14:58.211   881-12762 audio_route             android.hardware.audio.service       D  Apply path: low-latency-playbackP bt
2024-03-29 16:14:58.211  1150-1150  AOC                     aocd                                 D  F1:MSG: controller.cc, 273: AudioOutCtrl: cmd ID: 0x010e, tag: 0x25 [cntr = 6909]
2024-03-29 16:14:58.212  1150-1150  AOC                     aocd                                 D  F1:MSG: controller.cc, 811: AudioOutCtrl: ipc: audio_output_co, cmd ID: 0x010e, tag: 0x25, rc: 0
2024-03-29 16:14:58.212  1150-1150  AOC                     aocd                                 D  F1:MSG: controller.cc, 273: AudioOutCtrl: cmd ID: 0x010f, tag: 0x26 [cntr = 6910]
2024-03-29 16:14:58.212  1150-1150  AOC                     aocd                                 D  F1:Source 1 mastered by sink 2
2024-03-29 16:14:58.212   881-12762 audio_hw_aoc_route      android.hardware.audio.service       D  low-latency-playbackP bt 1
2024-03-29 16:14:58.212  1150-1150  AOC                     aocd                                 D  F1:AT Map:2 (LL:0), 1 EPs active, Power: Yes, Config: No
2024-03-29 16:14:58.212  1150-1150  AOC                     aocd                                 D  F1:Mixer 2 configuration changed: (2)
2024-03-29 16:14:58.213  1150-1150  AOC                     aocd                                 D  F1:Sink 2 Configuration changed: ULL (enabled)
2024-03-29 16:14:58.213  1150-1150  AOC                     aocd                                 D  F1:AHWSinkBT started: 48 samples (32-bit,2 ch,48 kHz) block 384
2024-03-29 16:14:58.213  1150-1150  AOC                     aocd                                 D  F1:SinkBT: A2DP SBC (16 bit, 44100Hz, stereo, compressed)
2024-03-29 16:14:58.214  1150-1150  AOC                     aocd                                 D  F1:MSG: controller.cc, 811: AudioOutCtrl: ipc: audio_output_co, cmd ID: 0x010f, tag: 0x26, rc: 0
2024-03-29 16:14:58.215  1150-1150  AOC                     aocd                                 D  H0:Mixer AMixBT: 480 samples (0002/0002) (ON SC)
2024-03-29 16:14:58.215   881-12762 audio_hw_waves          android.hardware.audio.service       D  waves_usecase_event: low-latency-playback start
2024-03-29 16:14:58.215   881-12762 audio_hw_waves          android.hardware.audio.service       D  waves_usecase_event: trigger to update tuning when first track is running.
2024-03-29 16:14:58.215   881-12762 audio_hw_bt             android.hardware.audio.service       D  set_latency_mode
2024-03-29 16:14:58.215   881-12762 audio_hw_bt             android.hardware.audio.service       D  set_a2dp_latency_mode: set latency mode 0 -> 0
2024-03-29 16:14:58.215   881-12762 audio_hw_a2dp           android.hardware.audio.service       I  a2dp_wrapper_set_latency_mode
2024-03-29 16:14:58.215  1150-1150  AOC                     aocd                                 D  H0:	 1: 0x403e1ab8
2024-03-29 16:14:58.215  1150-1150  AOC                     aocd                                 D  H0:AMixBT PostProcessing Config: BYPASS
2024-03-29 16:14:58.215  1150-1150  AOC                     aocd                                 D  H0:Mixer configuration updated
2024-03-29 16:14:58.215  1150-1150  AOC                     aocd                                 D  H0:Mixer state changed
2024-03-29 16:14:58.215  1150-1150  AOC                     aocd                                 D  F1:[AHWSinkBT] mix overrun by 3840
2024-03-29 16:14:58.215  1150-1150  AOC                     aocd                                 D  F1:MSG: controller.cc, 273: AudioOutCtrl: cmd ID: 0x00ce, tag: 0x27 [cntr = 6911]
2024-03-29 16:14:58.215  1150-1150  AOC                     aocd                                 D  F1:MSG: controller.cc, 811: AudioOutCtrl: ipc: audio_output_co, cmd ID: 0x0120, tag: 0xdc, rc: 0
2024-03-29 16:14:58.216  1150-1150  AOC                     aocd                                 D  F1:MSG: controller.cc, 273: AudioOutCtrl: cmd ID: 0x00c9, tag: 0x28 [cntr = 6912]
2024-03-29 16:14:58.216  1150-1150  AOC                     aocd                                 D  F1:MSG: controller.cc, 811: AudioOutCtrl: ipc: audio_output_co, cmd ID: 0x00c9, tag: 0x28, rc: 0
2024-03-29 16:14:58.236   955-1538  AudioFlinger            audioserver                          W  write blocked for 420 msecs, 8 delayed writes, thread 13
2024-03-29 16:14:58.236   881-881   audio_hw_soundtrigger   android.hardware.audio.service       D  updated Stream 3 active in soundtrigger
2024-03-29 16:14:58.236   881-881   audio_hw_output_stream  android.hardware.audio.service       D  update stream 3 active 1 gain 1.000000
2024-03-29 16:15:00.097 21440-5493  BluetoothAdapter        com.google.android.gms.persistent    D  isLeEnabled(): ON
2024-03-29 16:15:00.104  5250-5327  bt_stack                com.google.android.bluetooth         I  [INFO:le_scanning_manager.cc(202)] ScanFilterParamSetup in shim layer
2024-03-29 16:15:00.104  5250-5327  bt_stack                com.google.android.bluetooth         I  [INFO:le_scanning_manager.cc(202)] ScanFilterParamSetup in shim layer
2024-03-29 16:15:00.110  5250-5376  bt_stack                com.google.android.bluetooth         I  [INFO:le_scanning_manager.cc(160)] Unregister in shim layer, scanner_id:1
2024-03-29 16:15:00.113 21440-5493  NearbyDiscovery         com.google.android.gms.persistent    I  (REDACTED) FastPairScanner: isScreenOn=%s, isLocationEnabled=%s, disableLocationRequirement=%s, isDiscoveryScanningEnabled=%s, during24GhzWifiWarmingUpPeriod=%s
2024-03-29 16:15:00.114 21440-5493  NearbyDiscovery         com.google.android.gms.persistent    I  (REDACTED) FastPairScanner: Start scanning internally, %s
2024-03-29 16:15:00.115 21440-5493  BluetoothAdapter        com.google.android.gms.persistent    D  isLeEnabled(): ON
2024-03-29 16:15:00.121  5250-5376  bt_stack                com.google.android.bluetooth         I  [INFO:le_scanning_manager.cc(153)] RegisterScanner in shim layer
2024-03-29 16:15:00.122 21440-21698 BluetoothLeScanner      com.google.android.gms.persistent    D  onScannerRegistered() - status=0 scannerId=1 mScannerId=0
2024-03-29 16:15:00.132 21440-5493  NearbyDiscovery         com.google.android.gms.persistent    I  (REDACTED) FastPairScanner: Starting %s scanning
2024-03-29 16:15:00.132  5250-5327  bt_stack                com.google.android.bluetooth         I  [INFO:le_scanning_manager.cc(266)] ScanFilterEnable in shim layer
2024-03-29 16:15:00.134  5250-5327  bt_stack                com.google.android.bluetooth         I  [INFO:le_scanning_manager.cc(241)] ScanFilterAdd in shim layer
2024-03-29 16:15:00.135  5250-5327  bt_stack                com.google.android.bluetooth         I  [INFO:le_scanning_manager.cc(202)] ScanFilterParamSetup in shim layer
2024-03-29 16:15:00.136  5250-5327  bt_stack                com.google.android.bluetooth         I  [INFO:le_scanning_manager.cc(241)] ScanFilterAdd in shim layer
2024-03-29 16:15:00.137  5250-5327  bt_stack                com.google.android.bluetooth         I  [INFO:le_scanning_manager.cc(202)] ScanFilterParamSetup in shim layer
@patzly patzly added the bug Something isn't working label Mar 17, 2024
@patzly patzly moved this to Todo in Tack Android Mar 23, 2024
@patzly patzly added the help wanted Extra attention is needed label Mar 31, 2024
@patzly patzly moved this from Todo to Backlog in Tack Android May 16, 2024
@pkeir
Copy link

pkeir commented Jan 11, 2025

I see this too on my Android Nokia X20 (Android 14).

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working help wanted Extra attention is needed
Projects
Tack Android
Status: Backlog
Milestone
No milestone
Development

No branches or pull requests
2 participants
@pkeir @patzly