I copied the logcat when the bluetooth disconnect happened again.
I connected the bluetooth headset at 16:29 and the disconnect happened at about 16:58
logcat
08-01 16:29:43.917 D/BluetoothManagerService( 692): enable(com.android.systemui): mBluetooth =null mBinding = false mState = OFF
08-01 16:29:43.917 D/BluetoothManagerService( 692): enable returning
08-01 16:29:43.917 D/BluetoothManagerService( 692): MESSAGE_ENABLE(0): mBluetooth = null
08-01 16:29:43.943 I/ActivityManager( 692): Start proc 1225:com.android.bluetooth/1002 for service com.android.bluetooth/.btservice.AdapterService
08-01 16:29:44.210 D/BluetoothManagerService( 692): Bluetooth Adapter name changed to Fairphone FP2
08-01 16:29:44.210 D/BluetoothManagerService( 692): Stored Bluetooth name: Fairphone FP2
08-01 16:29:44.218 D/BluetoothManagerService( 692): BluetoothServiceConnection: com.android.bluetooth.btservice.AdapterService
08-01 16:29:44.221 D/BluetoothManagerService( 692): MESSAGE_BLUETOOTH_SERVICE_CONNECTED: 1
08-01 16:29:44.224 D/BluetoothManagerService( 692): Broadcasting onBluetoothServiceUp() to 5 receivers.
08-01 16:29:44.228 D/BluetoothManagerService( 692): MESSAGE_BLUETOOTH_STATE_CHANGE: OFF > BLE_TURNING_ON
08-01 16:29:44.228 D/BluetoothManagerService( 692): Sending BLE State Change: OFF > BLE_TURNING_ON
08-01 16:29:44.588 D/AutofillManagerService( 692): Close system dialogs
08-01 16:29:44.886 D/BluetoothManagerService( 692): Bluetooth Adapter address changed to 84:CF:BF:8B:A7:E7
08-01 16:29:44.898 D/BluetoothManagerService( 692): Stored Bluetoothaddress: 84:CF:BF:8B:A7:E7
08-01 16:29:44.932 D/BluetoothManagerService( 692): Bluetooth Adapter name changed to Fairphone FP2
08-01 16:29:44.933 D/BluetoothManagerService( 692): Stored Bluetooth name: Fairphone FP2
08-01 16:29:44.983 D/BluetoothManagerService( 692): MESSAGE_BLUETOOTH_STATE_CHANGE: BLE_TURNING_ON > BLE_ON
08-01 16:29:44.983 D/BluetoothManagerService( 692): Bluetooth is in LE only mode
08-01 16:29:44.983 D/BluetoothManagerService( 692): Binding Bluetooth GATT service
08-01 16:29:44.985 D/BluetoothManagerService( 692): Sending BLE State Change: BLE_TURNING_ON > BLE_ON
08-01 16:29:44.987 D/BluetoothManagerService( 692): BluetoothServiceConnection: com.android.bluetooth.gatt.GattService
08-01 16:29:44.987 D/BluetoothManagerService( 692): MESSAGE_BLUETOOTH_SERVICE_CONNECTED: 2
08-01 16:29:44.988 D/BluetoothManagerService( 692): BluetoothGatt Service is Up
08-01 16:29:44.990 D/BluetoothManagerService( 692): isBleAppPresent() count: 0
08-01 16:29:44.990 D/BluetoothManagerService( 692): Persisting Bluetooth Setting: 1
08-01 16:29:44.991 D/BluetoothManagerService( 692): MESSAGE_BLUETOOTH_STATE_CHANGE: BLE_ON > TURNING_ON
08-01 16:29:44.991 D/BluetoothManagerService( 692): Sending BLE State Change: BLE_ON > TURNING_ON
08-01 16:29:45.016 I/ActivityManager( 692): Start proc 1316:com.spotify.music/u0a85 for broadcast com.spotify.music/com.spotify.mobile.android.service.bluetooth.BluetoothBroadcastReceiver
08-01 16:29:45.345 D/BluetoothManagerService( 692): MESSAGE_BLUETOOTH_STATE_CHANGE: TURNING_ON > ON
08-01 16:29:45.346 D/BluetoothManagerService( 692): Broadcasting onBluetoothStateChange(true) to 9 receivers.
08-01 16:29:45.346 D/BluetoothManagerService( 692): Creating new ProfileServiceConnections object for profile: 1
08-01 16:29:45.367 D/BluetoothManagerService( 692): Sending BLE State Change: TURNING_ON > ON
08-01 16:29:45.371 I/Telecom ( 692): BluetoothPhoneService: queryPhoneState: BPSI.qPS@AWg
08-01 16:29:45.440 W/ActivityManager( 692): Unable to start service Intent { act=com.google.android.gms.ads.identifier.service.START pkg=com.google.android.gms } U=0: not found
08-01 16:29:45.467 I/Telecom ( 692): BluetoothDeviceManager: Device 20:74:CF:0F:A7:47 changed state to 1: BM.oR@AWo
08-01 16:29:45.467 I/Telecom ( 692): : - Got BluetoothHeadset: com.android.server.telecom.BluetoothHeadsetProxy@1742929: BMSL.oSC@AWs
08-01 16:29:45.816 E/ActivityThread( 1316): Failed to find provider info for com.facebook.katana.provider.AttributionIdProvider
08-01 16:29:46.540 I/ActivityManager( 692): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.spotify.music/.MainActivity bnds=[540,1019][714,1249] (has extras)} from uid 10092
08-01 16:29:49.106 I/PowerManagerService( 692): Going to sleep due to power button (uid 1000)…
08-01 16:29:49.599 I/DisplayPowerController( 692): Blocking screen off
08-01 16:29:49.600 I/DisplayPowerController( 692): Unblocked screen off after 1 ms
08-01 16:29:49.650 I/DisplayManagerService( 692): Display device changed state: “Integrierter Bildschirm”, OFF
08-01 16:29:50.119 I/DreamManagerService( 692): Entering dreamland.
[…]
08-01 16:53:31.592 W/ActivityManager( 692): setHasOverlayUi called on unknown pid: 6760
08-01 16:56:08.300 D/ConnectivityService( 692): Returning BLOCKED NetworkInfo to uid=10095
08-01 16:56:51.376 E/BatteryExternalStatsWorker( 692): modem info is invalid: ModemActivityInfo{ mTimestamp=0 mSleepTimeMs=0 mIdleTimeMs=0 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=0 mEnergyUsed=0}
08-01 16:56:56.101 I/ActivityManager( 692): Waited long enough for: ServiceRecord{d8d27cf u0 com.whatsapp/.messaging.MessageService}
08-01 16:57:00.599 E/BatteryExternalStatsWorker( 692): no controller energy info supplied for wifi
08-01 16:57:00.599 E/BatteryExternalStatsWorker( 692): no controller energy info supplied for bluetooth
08-01 16:57:00.621 E/BatteryExternalStatsWorker( 692): modem info is invalid: ModemActivityInfo{ mTimestamp=0 mSleepTimeMs=0 mIdleTimeMs=0 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=0 mEnergyUsed=0}
08-01 16:57:50.332 W/ActivityManager( 692): Stopping service due to app idle: u0a82 -1m9s235ms com.whatsapp/.messaging.MessageService
08-01 16:58:05.231 D/NetworkStats( 692): Resolving plan for NetworkTemplate: matchRule=MOBILE_ALL, subscriberId=262032…, matchSubscriberIds=[262032…]
08-01 16:58:05.257 D/NetworkStats( 692): Found active matching subId 1
08-01 16:58:05.267 D/NetworkStats( 692): Resolved to plan null
08-01 16:58:11.125 E/BatteryExternalStatsWorker( 692): modem info is invalid: ModemActivityInfo{ mTimestamp=0 mSleepTimeMs=0 mIdleTimeMs=0 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=0 mEnergyUsed=0}
08-01 16:58:15.839 I/ActivityManager( 692): Waited long enough for: ServiceRecord{b9ab9af u0 com.whatsapp/.messaging.MessageService}
08-01 16:59:11.585 E/BatteryExternalStatsWorker( 692): modem info is invalid: ModemActivityInfo{ mTimestamp=0 mSleepTimeMs=0 mIdleTimeMs=0 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=0 mEnergyUsed=0}
08-01 16:59:34.123 D/NetworkStats( 692): Resolving plan for NetworkTemplate: matchRule=MOBILE_ALL, subscriberId=262032…, matchSubscriberIds=[262032…]
08-01 16:59:34.126 D/NetworkStats( 692): Found active matching subId 1
08-01 16:59:34.134 D/NetworkStats( 692): Resolved to plan null
08-01 16:59:52.462 D/NetworkStats( 692): Resolving plan for NetworkTemplate: matchRule=MOBILE_ALL, subscriberId=262032…, matchSubscriberIds=[262032…]
08-01 16:59:52.463 D/NetworkStats( 692): Found active matching subId 1
08-01 16:59:52.467 D/NetworkStats( 692): Resolved to plan null
08-01 17:00:45.146 E/BatteryExternalStatsWorker( 692): modem info is invalid: ModemActivityInfo{ mTimestamp=0 mSleepTimeMs=0 mIdleTimeMs=0 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=0 mEnergyUsed=0}
08-01 17:02:30.138 I/chatty ( 692): uid=1000(system) batterystats-wo identical 4 lines
08-01 17:03:06.432 E/BatteryExternalStatsWorker( 692): modem info is invalid: ModemActivityInfo{ mTimestamp=0 mSleepTimeMs=0 mIdleTimeMs=0 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=0 mEnergyUsed=0}
08-01 17:03:06.922 W/ActivityManager( 692): Stopping service due to app idle: u0a82 -5m6s899ms com.whatsapp/.messaging.MessageService
08-01 17:04:08.012 I/wificond( 449): Pno scan stopped
08-01 17:04:15.621 I/wificond( 449): Pno scan started
08-01 17:04:22.946 I/ActivityManager( 692): Start proc 3554:org.lineageos.eleven/u0a11 for broadcast org.lineageos.eleven/.MediaButtonIntentReceiver
08-01 17:04:22.977 I/Telecom ( 692): BluetoothDeviceManager: Device 20:74:CF:0F:A7:47 changed state to 0: BM.oR@AYo
08-01 17:04:22.978 I/Telecom ( 692): BluetoothRouteManager: Message received: LOST_DEVICE.: BM.oR->BRM.pM_2@AYo
08-01 17:04:22.978 I/Telecom ( 692): CallAudioRouteStateMachine: Message received: DISCONNECT_BLUETOOTH=4, arg1=0: BM.oR->BRM.pM_2->CARSM.pM_DISCONNECT_BLUETOOTH@AYo
08-01 17:04:22.979 I/Telecom ( 692): Logging.Events: Non-call EVENT: AUDIO_ROUTE, Bluetooth disconnected: BM.oR->BRM.pM_2->CARSM.pM_DISCONNECT_BLUETOOTH@AYo
08-01 17:04:22.979 I/Telecom ( 692): CallAudioRouteStateMachine: Message received: SWITCH_BASELINE_ROUTE=1005, arg1=0: BM.oR->BRM.pM_2->CARSM.pM_DISCONNECT_BLUETOOTH->CARSM.pM_SWITCH_BASELINE_ROUTE@AYo
08-01 17:04:22.979 I/Telecom ( 692): CallAudioRouteStateMachine: Message received: SWITCH_EARPIECE=1001, arg1=0: BM.oR->BRM.pM_2->CARSM.pM_DISCONNECT_BLUETOOTH->CARSM.pM_SWITCH_BASELINE_ROUTE->CARSM.pM_SWITCH_EARPIECE@AYo
08-01 17:04:22.980 I/Telecom ( 692): Logging.Events: Non-call EVENT: AUDIO_ROUTE, Leaving state QuiescentBluetoothRoute: BM.oR->BRM.pM_2->CARSM.pM_DISCONNECT_BLUETOOTH->CARSM.pM_SWITCH_BASELINE_ROUTE->CARSM.pM_SWITCH_EARPIECE@AYo
08-01 17:04:22.980 I/Telecom ( 692): Logging.Events: Non-call EVENT: AUDIO_ROUTE, Entering state QuiescentEarpieceRoute: BM.oR->BRM.pM_2->CARSM.pM_DISCONNECT_BLUETOOTH->CARSM.pM_SWITCH_BASELINE_ROUTE->CARSM.pM_SWITCH_EARPIECE@AYo
08-01 17:04:22.985 I/Telecom ( 692): WiredHeadsetManager: ACTION_HEADSET_PLUG event, plugged in: false, : WHC.oADR@AYs
08-01 17:04:23.155 I/ActivityManager( 692): Start proc 3580:org.lineageos.eleven:main/u0a11 for service org.lineageos.eleven/.MusicPlaybackService
08-01 17:04:23.926 I/Telecom ( 692): WiredHeadsetManager: ACTION_HEADSET_PLUG event, plugged in: false, : WHC.oADR@AYw
08-01 17:04:28.566 I/Telecom ( 692): BluetoothDeviceManager: Device 20:74:CF:0F:A7:47 changed state to 1: BM.oR@AY0
08-01 17:04:28.657 I/Telecom ( 692): BluetoothPhoneService: queryPhoneState: BPSI.qPS@AY4
08-01 17:04:28.659 I/Telecom ( 692): BluetoothPhoneService: updateHeadsetWithCallState numActive 0, numHeld 0, callState 6, ringing number [da39a3ee5e6b4b0d3255bfef95601890afd80709], ringing type 128: BPSI.qPS@AY4
--------- beginning of main
08-01 17:04:28.687 D/audio_hw_primary( 417): adev_open_output_stream: Stream (0xadd64280) picks up usecase (compress-offload-playback)
08-01 17:04:28.688 I/AudioFlinger( 417): HAL output buffer size 32768 frames, normal sink buffer size 32768 frames
08-01 17:04:28.709 I/AudioFlinger( 417): AudioFlinger’s thread 0xadd73000 tid=3627 ready to run
08-01 17:04:28.711 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(3: compress-offload-playback) kvpairs: 20:74:CF:0F:A7:47=
08-01 17:04:28.712 I/AudioFlinger( 417): openOutput() this 0xaec32000, module 10 Device 20, SamplingRate 8000, Format 0x000001, Channels 1, flags 8001
08-01 17:04:28.712 D/audio_hw_primary( 417): adev_open_output_stream: enter: sample_rate(8000) channel_mask(0x1) devices(0x20) flags(0x8001) stream_handle(0xadd643c0)
08-01 17:04:28.712 E/audio_hw_primary( 417): adev_open_output_stream: Primary output is already opened
08-01 17:04:28.712 D/audio_hw_primary( 417): adev_open_output_stream: exit: ret -17
08-01 17:04:28.712 W/DeviceHAL( 417): Device 0xaec3b000 open_output_stream: File exists
08-01 17:04:28.712 I/AudioHwDevice( 417): openOutputStream(), HAL returned sampleRate 8000, Format 0x1, channelMask 0x1, status -61
08-01 17:04:28.712 W/APM_AudioPolicyManager( 417): checkOutputsForDevice() could not open output for device 20
08-01 17:04:28.713 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(3: compress-offload-playback) kvpairs: closing=true
08-01 17:04:28.714 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(3: compress-offload-playback) kvpairs: exiting=1
08-01 17:04:28.715 D/audio_hw_primary( 417): adev_close_output_stream: enter:stream_handle(0xadd64280)
08-01 17:04:28.715 D/audio_hw_primary( 417): out_standby: enter: stream (0xadd64280) usecase(3: compress-offload-playback)
08-01 17:04:28.716 E/audio_hw_primary( 417): offload_thread_loop: Compress handle is NULL
08-01 17:04:28.716 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: routing=0
08-01 17:04:28.717 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: fm_volume=0.0296826996
08-01 17:04:28.717 D/audio_hw_fm( 417): audio_extn_fm_set_parameters: set_fm_volume usecase
08-01 17:04:28.717 D/audio_hw_fm( 417): fm_set_volume: (0.029683)
08-01 17:04:28.718 D/AudioPolicyManagerCustom( 417): setDeviceConnectionStateInt() device: 0x80000008, state 1, address 20:74:CF:0F:A7:47 name TREKZ Titanium by AfterShokz
08-01 17:04:28.718 D/audio_hw_primary( 417): adev_set_parameters: enter: 20:74:CF:0F:A7:47=;connect=-2147483640
08-01 17:04:28.719 D/audio_hw_primary( 417): adev_open_input_stream: enter: sample_rate(48000) channel_mask(0xc) devices(0x80000008) stream_handle(0xaec2fe00) io_handle(38) source(1)
08-01 17:04:28.726 I/AudioFlinger( 417): AudioFlinger’s thread 0xad383100 tid=3629 ready to run
08-01 17:04:28.726 D/audio_hw_primary( 417): in_standby: enter: stream (0xaec2fe00) usecase(9: audio-record)
08-01 17:04:28.728 D/audio_hw_primary( 417): in_set_parameters: enter: kvpairs=20:74:CF:0F:A7:47=
08-01 17:04:28.728 D/audio_hw_primary( 417): in_standby: enter: stream (0xaec2fe00) usecase(9: audio-record)
08-01 17:04:28.732 I/Telecom ( 692): BluetoothDeviceManager: Device 20:74:CF:0F:A7:47 changed state to 2: BM.oR@AY8
08-01 17:04:28.733 I/Telecom ( 692): BluetoothRouteManager: Message received: NEW_DEVICE_CONNECTED.: BM.oR->BRM.pM_1@AY8
08-01 17:04:28.734 I/Telecom ( 692): CallAudioRouteStateMachine: Message received: CONNECT_BLUETOOTH=3, arg1=0: BM.oR->BRM.pM_1->CARSM.pM_CONNECT_BLUETOOTH@AY8
08-01 17:04:28.734 I/Telecom ( 692): Logging.Events: Non-call EVENT: AUDIO_ROUTE, Bluetooth connected: BM.oR->BRM.pM_1->CARSM.pM_CONNECT_BLUETOOTH@AY8
08-01 17:04:28.734 I/Telecom ( 692): CallAudioRouteStateMachine: Message received: SWITCH_BLUETOOTH=1002, arg1=0: BM.oR->BRM.pM_1->CARSM.pM_CONNECT_BLUETOOTH->CARSM.pM_SWITCH_BLUETOOTH@AY8
08-01 17:04:28.734 I/Telecom ( 692): Logging.Events: Non-call EVENT: AUDIO_ROUTE, Leaving state QuiescentEarpieceRoute: BM.oR->BRM.pM_1->CARSM.pM_CONNECT_BLUETOOTH->CARSM.pM_SWITCH_BLUETOOTH@AY8
08-01 17:04:28.734 I/Telecom ( 692): Logging.Events: Non-call EVENT: AUDIO_ROUTE, Entering state QuiescentBluetoothRoute: BM.oR->BRM.pM_1->CARSM.pM_CONNECT_BLUETOOTH->CARSM.pM_SWITCH_BLUETOOTH@AY8
08-01 17:04:28.736 I/Telecom ( 692): BluetoothRouteManager: Running runnable for testing
08-01 17:04:28.736 W/Telecom ( 692): Logging: Log.endSession was called with no session active.
08-01 17:04:28.755 D/audio_hw_primary( 417): adev_close_input_stream: enter:stream_handle(0xaec2fe00)
08-01 17:04:28.755 D/audio_hw_primary( 417): in_standby: enter: stream (0xaec2fe00) usecase(9: audio-record)
08-01 17:04:28.758 I/Telecom ( 692): WiredHeadsetManager: ACTION_HEADSET_PLUG event, plugged in: false, : WHC.oADA@AZE
08-01 17:04:28.765 D/CachedBluetoothDevice( 2963): onProfileStateChanged: profile A2DP newProfileState 1
08-01 17:04:29.186 W/AudioTrack( 1316): dead IAudioTrack, PCM, creating a new one from getPosition()
08-01 17:04:29.188 W/AudioFlinger( 417): createTrack_l(): mismatch between requested flags (00000008) and output flags (00000006)
08-01 17:04:29.191 D/AudioTrack( 1316): Client defaulted notificationFrames to 14700 for frameCount 44100
08-01 17:04:29.192 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: routing=2
08-01 17:04:29.193 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: fm_volume=0.0296826996
08-01 17:04:29.193 D/audio_hw_fm( 417): audio_extn_fm_set_parameters: set_fm_volume usecase
08-01 17:04:29.193 D/audio_hw_fm( 417): fm_set_volume: (0.029683)
08-01 17:04:29.194 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: fm_volume=0.0296826996
08-01 17:04:29.194 D/audio_hw_fm( 417): audio_extn_fm_set_parameters: set_fm_volume usecase
08-01 17:04:29.194 D/audio_hw_fm( 417): fm_set_volume: (0.029683)
08-01 17:04:29.196 D/audio_hw_primary( 417): start_output_stream: enter: stream(0xaec49ec0)usecase(1: low-latency-playback) devices(0x2)
08-01 17:04:29.196 D/audio_hw_primary( 417): start_output_stream: enter: usecase(1: low-latency-playback) devices(0x2)
08-01 17:04:29.196 D/audio_hw_primary( 417): select_devices: out_snd_device(2: speaker) in_snd_device(0: )
08-01 17:04:29.196 W/msm8974_platform( 417): Codec backend bitwidth 16, samplerate 48000
08-01 17:04:29.196 D/hardware_info( 417): hw_info_append_hw_type : device_name = speaker
08-01 17:04:29.196 I/msm8974_platform( 417): platform_send_audio_calibration: sending audio calibration for snd_device(2) acdb_id(14)
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> send_audio_cal, acdb_id = 14, path = 0
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> send_adm_topology
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> send_asm_topology
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> send_audtable
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> AUDIO_SET_AUDPROC_CAL
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> send_audvoltable
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> AUDIO_SET_AUDPROC_VOL_CAL
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> send_afe_cal
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE
08-01 17:04:29.196 D/ACDB-LOADER( 417): ACDB -> AUDIO_SET_AFE_CAL
08-01 17:04:29.196 D/audio_route( 417): Apply path: speaker
08-01 17:04:29.201 D/audio_route( 417): Apply path: low-latency-playback
08-01 17:04:29.204 D/audio_hw_primary( 417): select_devices: done
08-01 17:04:29.749 I/AudioFlinger( 417): BUFFER TIMEOUT: remove(4098) from active list on thread 0xada03280
08-01 17:04:30.864 D/CachedBluetoothDevice( 2963): onProfileStateChanged: profile A2DP newProfileState 2
08-01 17:04:30.870 I/chatty ( 1225): uid=1002(bluetooth) com.android.bluetooth expire 10 lines
08-01 17:04:30.897 D/AudioPolicyManagerCustom( 417): setForceUse() usage 1, config 0, mPhoneState 0
08-01 17:04:30.898 D/AudioPolicyManagerCustom( 417): setDeviceConnectionStateInt() device: 0x80, state 1, address 20:74:CF:0F:A7:47 name TREKZ Titanium by AfterShokz
08-01 17:04:30.898 D/audio_hw_primary( 417): adev_set_parameters: enter: 20:74:CF:0F:A7:47=;connect=128
08-01 17:04:30.899 I/AudioFlinger( 417): openOutput() this 0xaec32000, module 18 Device 80, SamplingRate 44100, Format 0x000001, Channels 3, flags 0
08-01 17:04:30.899 I/bt_a2dp_hw( 417): adev_open_output_stream: opening output
08-01 17:04:30.899 I/bt_a2dp_hw( 417): a2dp_command: starting up or recovering from previous error
08-01 17:04:30.899 I/bt_a2dp_hw( 417): skt_connect: connect to /data/misc/bluedroid/.a2dp_ctrl (sz 256)
08-01 17:04:30.899 I/chatty ( 1225): uid=1002(bluetooth) com.android.bluetooth expire 33 lines
08-01 17:04:30.899 I/bt_a2dp_hw( 417): skt_connect: connected to stack fd = 26
08-01 17:04:30.900 I/bt_a2dp_hw( 417): a2dp_read_output_audio_config: got output codec capability: sample_rate=0x1 bits_per_sample=0x1 channel_mode=0x3
08-01 17:04:30.900 I/chatty ( 1225): uid=1002(bluetooth) com.android.bluetooth expire 15 lines
08-01 17:04:30.900 I/bt_a2dp_hw( 417): a2dp_write_output_audio_config: sent output codec config: sample_rate=0x1 bits_per_sample=0x1 channel_mode=0x2
08-01 17:04:30.901 I/bt_a2dp_hw( 417): a2dp_read_output_audio_config: got output codec capability: sample_rate=0x1 bits_per_sample=0x1 channel_mode=0x3
08-01 17:04:30.901 I/bt_a2dp_hw( 417): adev_open_output_stream: Output stream config: format=0x1 sample_rate=44100 channel_mask=0x3 buffer_sz=7168
08-01 17:04:30.910 E/Spotify ( 1316): [main] AutomaticWazeLauncher failed
08-01 17:04:30.910 E/Spotify ( 1316): java.lang.Throwable: Failed resolving ‘hm://external-accessory-categorizer/v1/categorize/TREKZ%20Titanium%20by%20AfterShokz’, got status code: 0
08-01 17:04:30.910 E/Spotify ( 1316): [main] Categorization failed. Could not resolve category for CarAccessoryConnector: java.lang.Throwable: Failed resolving ‘hm://external-accessory-categorizer/v1/categorize/TREKZ%20Titanium%20by%20AfterShokz’, got status code: 0
08-01 17:04:30.911 E/Spotify ( 1316): [main] Could not subscribe to category and foreground state: java.lang.Throwable: Failed resolving ‘hm://external-accessory-categorizer/v1/categorize/TREKZ%20Titanium%20by%20AfterShokz’, got status code: 0
08-01 17:04:30.912 E/Spotify ( 1316): [main] Categorization failed. Could not resolve category for CarConnectedState: java.lang.Throwable: Failed resolving ‘hm://external-accessory-categorizer/v1/categorize/TREKZ%20Titanium%20by%20AfterShokz’, got status code: 0
08-01 17:04:31.152 I/AudioFlinger( 417): HAL output buffer size 896 frames, normal sink buffer size 896 frames
08-01 17:04:31.165 I/AudioFlinger( 417): AudioFlinger’s thread 0xad383680 tid=3660 ready to run
08-01 17:04:31.175 I/bt_a2dp_hw( 417): out_set_parameters: state 3 kvpairs a2dp_sink_address=20:74:CF:0F:A7:47
08-01 17:04:31.176 I/hash_map_utils( 417): key: ‘a2dp_sink_address’ value: ‘20:74:CF:0F:A7:47’
08-01 17:04:31.176 I/bt_a2dp_hw( 417): suspend_audio_datapath: state 3
08-01 17:04:31.177 I/bt_a2dp_hw( 417): skt_disconnect: fd -1
08-01 17:04:31.181 W/AudioFlinger( 417): moveEffectChain_l() effect chain for session 0 not on source thread 0xada03280
08-01 17:04:31.190 I/AudioFlinger( 417): HAL output buffer size 896 frames, normal sink buffer size 896 frames
08-01 17:04:31.200 I/AudioFlinger( 417): AudioFlinger’s thread 0xac5035c0 tid=3661 ready to run
08-01 17:04:31.208 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: routing=0
08-01 17:04:31.228 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: fm_volume=0.0296826996
08-01 17:04:31.228 D/audio_hw_fm( 417): audio_extn_fm_set_parameters: set_fm_volume usecase
08-01 17:04:31.228 D/audio_hw_fm( 417): fm_set_volume: (0.029683)
08-01 17:04:31.239 D/audio_hw_primary( 417): out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: fm_volume=0.0296826996
08-01 17:04:31.239 D/audio_hw_fm( 417): audio_extn_fm_set_parameters: set_fm_volume usecase
08-01 17:04:31.239 D/audio_hw_fm( 417): fm_set_volume: (0.029683)
08-01 17:04:31.241 D/audio_hw_primary( 417): adev_set_parameters: enter: A2dpSuspended=false
08-01 17:04:31.241 I/bt_a2dp_hw( 417): adev_set_parameters: state 5
08-01 17:04:31.241 I/bt_a2dp_hw( 417): out_set_parameters: state 5 kvpairs A2dpSuspended=false
08-01 17:04:31.241 I/hash_map_utils( 417): key: ‘A2dpSuspended’ value: ‘false’
08-01 17:04:31.250 I/Telecom ( 692): WiredHeadsetManager: ACTION_HEADSET_PLUG event, plugged in: false, : WHC.oADA@AZI
08-01 17:04:31.264 I/vol.Events( 2963): writeEvent level_changed STREAM_SYSTEM 5
08-01 17:04:31.283 V/MediaRouter( 1316): Adding route: RouteInfo{ name=TREKZ Titanium by AfterShokz, description=Bluetooth-Audio, status=null, category=RouteCategory{ name=System types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
08-01 17:04:31.285 V/MediaRouter( 1316): Audio routes updated: AudioRoutesInfo{ type=SPEAKER, bluetoothName=TREKZ Titanium by AfterShokz }, a2dp=true
08-01 17:04:31.286 V/MediaRouter( 1316): Selecting route: RouteInfo{ name=TREKZ Titanium by AfterShokz, description=Bluetooth-Audio, status=null, category=RouteCategory{ name=System types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
08-01 17:04:31.289 V/MediaRouter( 1316): Selecting route: RouteInfo{ name=TREKZ Titanium by AfterShokz, description=Bluetooth-Audio, status=null, category=RouteCategory{ name=System types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
08-01 17:04:31.289 I/vol.Events( 2963): writeEvent level_changed STREAM_BLUETOOTH_SCO 7
08-01 17:04:31.293 V/MediaRouter( 2963): Adding route: RouteInfo{ name=TREKZ Titanium by AfterShokz, description=Bluetooth-Audio, status=null, category=RouteCategory{ name=System types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
08-01 17:04:31.294 V/MediaRouter( 2963): Audio routes updated: AudioRoutesInfo{ type=SPEAKER, bluetoothName=TREKZ Titanium by AfterShokz }, a2dp=true
08-01 17:04:31.294 V/MediaRouter( 2963): Selecting route: RouteInfo{ name=TREKZ Titanium by AfterShokz, description=Bluetooth-Audio, status=null, category=RouteCategory{ name=System types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
08-01 17:04:31.301 D/AudioPolicyManagerCustom( 417): setForceUse() usage 1, config 0, mPhoneState 0
08-01 17:04:31.342 I/chatty ( 1225): uid=1002(bluetooth) com.android.bluetooth expire 25 lines
08-01 17:04:31.343 I/vol.Events( 2963): writeEvent level_changed STREAM_MUSIC 7
08-01 17:04:31.729 D/audio_hw_primary( 417): out_standby: enter: stream (0xaec49ec0) usecase(1: low-latency-playback)
08-01 17:04:31.799 D/hardware_info( 417): hw_info_append_hw_type : device_name = speaker
08-01 17:04:32.186 W/AudioTrack( 1316): dead IAudioTrack, PCM, creating a new one from getPosition()
08-01 17:04:32.201 W/AudioFlinger( 417): createTrack_l(): mismatch between requested flags (00000008) and output flags (00000000)
08-01 17:04:32.210 D/AudioTrack( 1316): Client defaulted notificationFrames to 22050 for frameCount 44100
08-01 17:04:32.217 I/bt_a2dp_hw( 417): out_set_parameters: state 5 kvpairs a2dp_sink_address=20:74:CF:0F:A7:47;routing=128
08-01 17:04:32.218 I/hash_map_utils( 417): key: ‘routing’ value: ‘128’
08-01 17:04:32.218 I/hash_map_utils( 417): key: ‘a2dp_sink_address’ value: ‘20:74:CF:0F:A7:47’
08-01 17:04:32.239 I/bt_a2dp_hw( 417): start_audio_datapath: state 5
08-01 17:04:32.241 I/chatty ( 1225): uid=1002(bluetooth) com.android.bluetooth expire 11 lines
08-01 17:04:32.273 I/chatty ( 1225): uid=1002(bluetooth) com.android.bluetooth expire 27 lines
08-01 17:04:32.273 I/bt_a2dp_hw( 417): skt_connect: connect to /data/misc/bluedroid/.a2dp_data (sz 7168)
08-01 17:04:32.274 I/bt_a2dp_hw( 417): skt_connect: connected to stack fd = 24
08-01 17:04:32.732 I/AudioFlinger( 417): BUFFER TIMEOUT: remove(4097) from active list on thread 0xad383680
08-01 17:04:33.124 I/chatty ( 692): uid=1000(system) Binder:692_1 expire 5 lines
08-01 17:04:33.161 I/chatty ( 692): uid=1000(system) Binder:692_9 expire 6 lines
08-01 17:04:34.207 I/bt_a2dp_hw( 417): suspend_audio_datapath: state 1
08-01 17:04:34.227 I/bt_a2dp_hw( 417): skt_disconnect: fd 24
08-01 17:04:34.232 I/chatty ( 1225): uid=1002(bluetooth) com.android.bluetooth expire 12 lines
08-01 17:04:43.912 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:04:43.913 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:04:43.913 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:04:43.913 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:04:53.234 E/BatteryExternalStatsWorker( 692): modem info is invalid: ModemActivityInfo{ mTimestamp=0 mSleepTimeMs=0 mIdleTimeMs=0 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=0 mEnergyUsed=0}
08-01 17:05:00.281 I/chatty ( 692): uid=1000(system) Binder:692_2 expire 8 lines
08-01 17:05:46.354 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:05:48.494 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:05:48.560 I/rmt_storage( 314): rmt_storage_connect_cb: clnt_h=0x26 conn_h=0xb508c128
08-01 17:05:48.560 I/rmt_storage( 314): rmt_storage_rw_iovec_cb: /boot/modem_fs2: clnt_h=0x2: req_h=0x2a msg_id=3: R/W request received
08-01 17:05:48.560 I/rmt_storage( 314): wakelock acquired: 1, error no: 42
08-01 17:05:48.561 I/rmt_storage( 314): rmt_storage_client_thread: /boot/modem_fs2: clnt_h=0x2 Unblock worker thread (th_id: -1259067024)
08-01 17:05:48.778 I/rmt_storage( 314): rmt_storage_client_thread: /boot/modem_fs2: clnt_h=0x2: req_h=0x2a msg_id=3: Bytes written = 1572864
08-01 17:05:48.778 I/rmt_storage( 314): rmt_storage_client_thread: /boot/modem_fs2: clnt_h=0x2: req_h=0x2a msg_id=3: Send response: res=0 err=0
08-01 17:05:48.778 I/rmt_storage( 314): rmt_storage_client_thread: /boot/modem_fs2: clnt_h=0x2 About to block rmt_storage client thread (th_id: -1259067024) wakelock released: 1, error no: 0
08-01 17:05:48.778 I/rmt_storage( 314):
08-01 17:05:48.779 I/rmt_storage( 314): rmt_storage_disconnect_cb: clnt_h=0x0x26 conn_h=0x0xb508c128
08-01 17:06:09.090 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:06:09.735 I/chatty ( 692): uid=1000(system) batterystats-wo expire 12 lines
08-01 17:06:09.740 E/WifiHAL ( 312): wifi_get_logger_supported_feature_set: Error -3 happened.
08-01 17:06:09.753 I/chatty ( 692): uid=1000(system) WifiStateMachin expire 12 lines
08-01 17:06:09.758 E/WifiHAL ( 312): wifi_get_logger_supported_feature_set: Error -3 happened.
08-01 17:06:09.800 E/BatteryExternalStatsWorker( 692): no controller energy info supplied for wifi
08-01 17:06:09.810 E/BatteryExternalStatsWorker( 692): no controller energy info supplied for bluetooth
08-01 17:06:09.845 E/BatteryExternalStatsWorker( 692): modem info is invalid: ModemActivityInfo{ mTimestamp=0 mSleepTimeMs=0 mIdleTimeMs=0 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=0 mEnergyUsed=0}
08-01 17:06:10.110 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:06:56.893 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:06:56.896 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:06:56.896 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:06:56.896 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:06:56.896 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:06:57.544 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:07:01.529 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:07:17.300 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:07:17.301 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:07:17.301 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:07:17.301 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:07:29.075 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:07:29.076 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:07:29.076 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:07:29.076 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:07:34.093 I/WifiHAL ( 312): event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xa
08-01 17:07:34.095 I/wpa_supplicant( 3744): p2p0: CTRL-EVENT-AVOID-FREQ ranges=
08-01 17:07:53.514 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:07:53.731 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:07:53.731 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:07:53.731 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:07:53.731 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:07:54.577 E/BatteryExternalStatsWorker( 692): modem info is invalid: ModemActivityInfo{ mTimestamp=0 mSleepTimeMs=0 mIdleTimeMs=0 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=0 mEnergyUsed=0}
08-01 17:07:55.516 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:07:56.433 I/chatty ( 692): uid=1000 system_server expire 4 lines
08-01 17:07:56.466 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:07:56.467 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:07:56.467 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:07:56.467 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:07:57.492 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:07:58.021 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:07:58.021 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:07:58.021 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:07:58.021 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:08:01.580 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:08:01.581 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:08:01.581 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:08:01.581 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:08:14.257 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:08:14.258 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:08:14.258 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:08:14.258 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:08:16.562 I/wpa_supplicant( 3744): p2p0: CTRL-EVENT-AVOID-FREQ ranges=
08-01 17:08:16.563 I/WifiHAL ( 312): event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xa
08-01 17:08:25.531 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:08:26.503 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:08:26.503 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:08:26.503 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:08:26.503 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:08:38.190 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:08:38.191 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:08:38.191 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:08:38.192 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:08:40.399 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:08:40.400 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:08:40.400 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:08:40.400 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:08:40.584 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:08:40.584 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:08:40.585 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:08:40.585 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:08:40.601 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:08:40.602 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:08:40.602 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:08:40.602 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:08:40.609 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000011000000514f454d484f4f4bfa0308000100000000]
08-01 17:08:40.609 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:08:40.609 D/QcrilMsgTunnelSocket( 6457): Response ID 525306 is not served in this process.
08-01 17:08:40.609 D/QcrilMsgTunnelSocket( 6457): To broadcast an Intent via the notifier to external apps
08-01 17:08:40.609 D/QcrilMsgTunnelIfaceManager( 6457): handleMessage what = 0
08-01 17:08:40.612 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000011000000514f454d484f4f4bfa0308000100000000]
08-01 17:08:40.612 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:08:40.612 D/QcrilMsgTunnelSocket( 6457): Response ID 525306 is not served in this process.
08-01 17:08:40.612 D/QcrilMsgTunnelSocket( 6457): To broadcast an Intent via the notifier to external apps
08-01 17:08:40.613 D/QcrilMsgTunnelIfaceManager( 6457): handleMessage what = 0
08-01 17:08:51.302 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:08:51.302 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:08:51.303 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:08:51.303 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:08:55.516 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:08:59.514 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:09:00.935 I/wpa_supplicant( 3744): p2p0: CTRL-EVENT-AVOID-FREQ ranges=
08-01 17:09:00.937 I/WifiHAL ( 312): event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xa
08-01 17:09:01.180 I/WifiHAL ( 312): event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xa
08-01 17:09:01.180 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:09:01.181 I/wpa_supplicant( 3744): p2p0: CTRL-EVENT-AVOID-FREQ ranges=
08-01 17:09:01.181 I/wpa_supplicant( 3744): p2p0: CTRL-EVENT-AVOID-FREQ ranges=
08-01 17:09:01.183 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:09:01.183 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:09:01.183 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:09:01.183 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:09:01.222 I/wpa_supplicant( 3744): p2p0: CTRL-EVENT-AVOID-FREQ ranges=
08-01 17:09:01.222 I/WifiHAL ( 312): event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xa
08-01 17:09:01.985 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:09:09.993 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:09:22.918 D/QcrilMsgTunnelSocket( 6457): readRilMessage: Buffer = [B@2cad907 HexData = [010000000404000012000000514f454d484f4f4b1d04080002000000ffff]
08-01 17:09:22.919 D/QcrilMsgTunnelSocket( 6457): Rcvd UNSOL response with 28 bytes data for SUB0
08-01 17:09:22.919 D/QcrilMsgTunnelSocket( 6457): Rac Change (PDC) data: [-1, -1]
08-01 17:09:22.919 D/QcrilMsgTunnelSocket( 6457): Broadcasting intent ACTION_RAC_CHANGED
08-01 17:09:52.030 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:09:53.620 I/qdhwcomposer( 320): handle_blank_event: dpy:0 panel power state: 0
08-01 17:10:10.622 I/rmt_storage( 314): rmt_storage_connect_cb: clnt_h=0x27 conn_h=0xb508c128
08-01 17:10:10.623 I/rmt_storage( 314): rmt_storage_rw_iovec_cb: /boot/modem_fs1: clnt_h=0x1: req_h=0x2b msg_id=3: R/W request received
08-01 17:10:10.623 I/rmt_storage( 314): wakelock acquired: 1, error no: 42
08-01 17:10:10.624 I/rmt_storage( 314): rmt_storage_client_thread: /boot/modem_fs1: clnt_h=0x1 Unblock worker thread (th_id: -1258030736)
08-01 17:10:10.815 I/rmt_storage( 314): rmt_storage_client_thread: /boot/modem_fs1: clnt_h=0x1: req_h=0x2b msg_id=3: Bytes written = 1572864
08-01 17:10:10.815 I/rmt_storage( 314): rmt_storage_client_thread: /boot/modem_fs1: clnt_h=0x1: req_h=0x2b msg_id=3: Send response: res=0 err=0
08-01 17:10:10.815 I/rmt_storage( 314): rmt_storage_client_thread: /boot/modem_fs1: clnt_h=0x1 About to block rmt_storage client thread (th_id: -1258030736) wakelock released: 1, error no: 0
…