Open patzly opened 7 months ago
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
After a random amount of seconds/minutes. Logs do not say anything important when metronome is started and doesn't play anything anymore: