Oooh. Ohhh. Lineage 15.1 (testing)

I can’t reproduce here. Playback with Spotify to a Bluetooth speaker works fine over long time.

Can you post logcat when this happens?

Chris

I tested Bluetooth playback with the stock music App for approx. 40 minutes each before stopping it myself on a Panasonic RF-D20BT radio and a Marantz NR1508 receiver … no problem.
I don’t have a Bluetooth headset or Bluetooth headphones or Spotify, though.

New build. Built from incorporating lineage review comments. Also fixed (hopefully): Proximity Sensor calibration app.

Chris

7 Likes

Seems to have fixed it for me. Went through all steps and rebooted sucessfully

1 Like

Thanks a lot for your hard work, not missing a thing from my old OS :slight_smile: everything seems to work so far

I can confirm that as well. It works fine now!!!

I can confirm that as well. I do not have spotify though. I paired my FP2 with my UE Boom and the playback through “Radiodroid” worked perfectly for about 25-30 minutes. Then it stopped. I then had to press the play button again.

Since yesterday, daily driver for me as well :smiley:

I put those shortcuts that I had before and consider useful :slight_smile:

  • Battery percentage in the status bar
  • Flight mode shortcut with the power button
  • Power button ends call (accessibility)

Can you please provide logcat when this happens?

Chris

of course. I will do it this evening, when I am home.

Please check the following …

Settings - Security & privacy - Trust - Privacy Guard - (for a stock App enable “Show built-in apps” in the three-dot menu) - long-tap on the App in question for a list of permissions - Is “Run in background” enabled there?

For my stock music App it was enabled by default.

1 Like

Yes, it is enabled. Thanks for the hint anyway. I will provide logcat this eveving, when it happens again.

2 Likes

I installed the new build today and tested the BT connection with a Pebble Time Round and a Pebble 2. BT connection works well for both devices. Automatic reconnect after disconnect also works without problems so far. This is especially a positive surprise for the Pebble 2, as the Pebble 2 uses BTLE only and there were often connection problems with earlier Android versions (FPOOS and LOS14).

2 Likes

Did you experience those connection problems with the official Pebble app or with Gadgetbridge? I get issues if both run, but when only one runs it works perfectly fine. On LOS14.

There were less problems when only one app was installed, but nevertheless there were still connection problems with the official Pebble app as well as with Gadgetbridge.

And it works well now with LOS15 even when both apps are installed. Gadgetbridge is installed for one user and the official Pebble app for another user (same as with LOS14 before).

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

1 Like

Can you please post full logcat with some downloadable link? Forgot to ask, can you also get /proc/kmsg?

Chris

Update: I did not have the problem today. The music played for almost 55 mins before the battery of the UE Boom ran out…

@Pitey do you have another BT device that you could test?

Chris

Build installed via TWRP 3.2.2-0 (Releases)

The following things work for me so far:

  • call / be called
  • send SMS / receive SMS
  • internet via WiFi
  • internet via mobile network
  • location
  • compass
  • screenshot
  • main camera (new module, flash works correctly with Open Camera)
  • selfie camera (new module)
  • USB connection to PC / MTP
  • alarm
  • MyPhoneExplorer 1.8.9
  • root (Settings - Developer Settings - Root Access)
  • USB OTG
  • WiFi hotspot
  • Bluetooth (music playback, data connection)
  • FM radio

Confirmed, works now.

I tested my TomTom Sports Watch with Bluetooth - worked fine.
@oli.sax; how do you end flight mode with power button?