Database Info

[Q] HTC Sensation with CM10.1 crashes after ~1-4 hours uptime

For a long time I had the stock ROM running on my HTC Sensation. But last week I've been wondering how nice the world would look like with an unlocked and rooted phone. After reading some guides I've succeeded and now have an unlocked HTC sensation with S-off and root. Then I flashed Cyanogenmod 10.1 from this thread: http://forum.xda-developers.com/showthread.php?t=1930403.
On the first look my phone is running fine and I have no issues with it, everything works as expected.
However, the phone seems to be rebooting after a while. This can happen at any moment (in use, or just idle) mostly after a couple of hours. Sometimes it reboots pretty quickly after the previous reboot.. It wasn't telling me what was happening or that something went wrong..
So I've started a logcat with adb to see what was happening at the time of the crash and then saw the following stacktrace:
W/dalvikvm(22628): threadid=36: thread exiting with uncaught exception (group=0x40b16930)
E/AndroidRuntime(22628): *** FATAL EXCEPTION IN SYSTEM PROCESS: NetworkStats
E/AndroidRuntime(22628): java.lang.RuntimeException: Error receiving broadcast Intent { act=com.android.server.action.NETWORK_STATS_POLL flg=0x14 (has extras) } in [email protected]
E/AndroidRuntime(22628): at android.app.LoadedApk$ReceiverDispatcher$Args.run(LoadedApk.java:768)
E/AndroidRuntime(22628): at android.os.Handler.handleCallback(Handler.java:725)
E/AndroidRuntime(22628): at android.os.Handler.dispatchMessage(Handler.java:92)
E/AndroidRuntime(22628): at android.os.Looper.loop(Looper.java:137)
E/AndroidRuntime(22628): at android.os.HandlerThread.run(HandlerThread.java:60)
E/AndroidRuntime(22628): Caused by: java.lang.IllegalArgumentException: tried recording negative data
E/AndroidRuntime(22628): at android.net.NetworkStatsHistory.recordData(NetworkStatsHistory.java:302)
E/AndroidRuntime(22628): at android.net.NetworkStatsHistory.recordHistory(NetworkStatsHistory.java:375)
E/AndroidRuntime(22628): at android.net.NetworkStatsHistory.recordEntireHistory(NetworkStatsHistory.java:351)
E/AndroidRuntime(22628): at com.android.server.net.NetworkStatsCollection.recordHistory(NetworkStatsCollection.java:220)
E/AndroidRuntime(22628): at com.android.server.net.NetworkStatsCollection.read(NetworkStatsCollection.java:282)
E/AndroidRuntime(22628): at com.android.server.net.NetworkStatsCollection.read(NetworkStatsCollection.java:256)
E/AndroidRuntime(22628): at com.android.server.net.NetworkStatsRecorder$CombiningRewriter.read(NetworkStatsRecorder.java:280)
E/AndroidRuntime(22628): at com.android.internal.util.FileRotator.readFile(FileRotator.java:367)
E/AndroidRuntime(22628): at com.android.internal.util.FileRotator.rewriteSingle(FileRotator.java:242)
E/AndroidRuntime(22628): at com.android.internal.util.FileRotator.rewriteActive(FileRotator.java:184)
E/AndroidRuntime(22628): at com.android.server.net.NetworkStatsRecorder.forcePersistLocked(NetworkStatsRecorder.java:223)
E/AndroidRuntime(22628): at com.android.server.net.NetworkStatsRecorder.maybePersistLocked(NetworkStatsRecorder.java:210)
E/AndroidRuntime(22628): at com.android.server.net.NetworkStatsService.performPollLocked(NetworkStatsService.java:1009)
E/AndroidRuntime(22628): at com.android.server.net.NetworkStatsService.performPoll(NetworkStatsService.java:952)
E/AndroidRuntime(22628): at com.android.server.net.NetworkStatsService.access$700(NetworkStatsService.java:139)
E/AndroidRuntime(22628): at com.android.server.net.NetworkStatsService$4.onReceive(NetworkStatsService.java:741)
E/AndroidRuntime(22628): at android.app.LoadedApk$ReceiverDispatcher$Args.run(LoadedApk.java:758)
E/AndroidRuntime(22628): ... 4 more
On google I could not find any crash related to NetworkStats or any similar problems. So I was wondering if you guys would know what is going wrong here and what the solution is to this issue.

I recently developed this problem out of nowhere. A full wipe and fresh i stall seems to have solved it.
Sent from my HTC Sensation using xda app-developers app

Skipjacks said:
I recently developed this problem out of nowhere. A full wipe and fresh i stall seems to have solved it.
Sent from my HTC Sensation using xda app-developers app
Click to expand...
Click to collapse
A full wipe and fresh install is a bit extreme in my eyes when I don't know what is really causing this issue.. I've been looking a bit deeper and saw that the network usage in the settings would also crash everytime i tried to open it (with a similar stacktrace).
Maybe it saved some invalid value to the netstats (as the stacktrace mentions IllegalArgumentException: tried recording negative data) at some time so I removed all the files from /data/system/netstats directory and now it seems to work again

Hello,
My phone has been restarting for two days now (it is Samsung Galaxy S, however). It gives similar output as yours in logcat.
I have updated it to latest version of CM, but no luck.
Reboots happen randomly to my knowledge, but I can trigger them if I uninstall an app. During uninstalling the phone soft reboots.
Can you also trigger reboot if you uninstall an app?
Here is a relevant portion of a logcat:
Code:
06-13 11:07:43.578 I/ActivityManager(29427): START u0 {act=android.intent.action.VIEW cmp=com.android.packageinstaller/.UninstallAppProgress (has extras)} from pid 31430
06-13 11:07:43.625 D/AudioHardware(29209): AudioHardware pcm playback is exiting standby.
06-13 11:07:43.625 V/AudioHardware(29209): open pcm_out driver
06-13 11:07:43.625 D/AudioHardware(29209): openPcmOut_l() mPcmOpenCnt: 0
06-13 11:07:43.695 V/AudioHardware(29209): openMixer_l() mMixerOpenCnt: 0
06-13 11:07:43.695 V/AudioHardware(29209): open playback normal
06-13 11:07:43.695 V/AudioHardware(29209): write() wakeup setting route SPK
06-13 11:07:43.902 I/PackageManager(29427): Removing non-system package:org.cohortor.gstrings
06-13 11:07:43.910 I/ActivityManager(29427): Force stopping package org.cohortor.gstrings appid=10095 user=-1
06-13 11:07:44.019 W/PackageManager(29427): Couldn't delete native library directory /data/app-lib/org.cohortor.gstrings
06-13 11:07:44.050 I/ActivityManager(29427): Displayed com.android.packageinstaller/.UninstallAppProgress: +433ms
06-13 11:07:44.496 D/dalvikvm(31430): GC_EXPLICIT freed 138K, 67% free 2574K/7696K, paused 9ms+3ms, total 53ms
06-13 11:07:44.941 I/Choreographer(29538): Skipped 34 frames! The application may be doing too much work on its main thread.
06-13 11:07:44.949 I/ActivityManager(29427): Force stopping package org.cohortor.gstrings appid=10095 user=0
06-13 11:07:45.062 D/dalvikvm(31200): GC_EXPLICIT freed 914K, 65% free 2760K/7696K, paused 2ms+11ms, total 83ms
06-13 11:07:45.086 D/dalvikvm(29679): GC_EXPLICIT freed 869K, 27% free 9753K/13236K, paused 3ms+13ms, total 122ms
06-13 11:07:45.144 I/InputReader(29427): Reconfiguring input devices. changes=0x00000010
06-13 11:07:46.269 D/dalvikvm(29427): GC_EXPLICIT freed 1482K, 20% free 9382K/11636K, paused 684ms+38ms, total 1106ms
06-13 11:07:46.269 D/dalvikvm(29427): WAIT_FOR_CONCURRENT_GC blocked 295ms
06-13 11:07:46.273 D/dalvikvm(29427): WAIT_FOR_CONCURRENT_GC blocked 246ms
06-13 11:07:46.273 D/dalvikvm(29427): WAIT_FOR_CONCURRENT_GC blocked 163ms
06-13 11:07:46.273 D/dalvikvm(29427): WAIT_FOR_CONCURRENT_GC blocked 150ms
06-13 11:07:46.273 D/dalvikvm(29427): WAIT_FOR_CONCURRENT_GC blocked 97ms
06-13 11:07:46.277 D/dalvikvm(29427): WAIT_FOR_CONCURRENT_GC blocked 115ms
06-13 11:07:46.488 I/Choreographer(29679): Skipped 69 frames! The application may be doing too much work on its main thread.
06-13 11:07:46.754 W/dalvikvm(29427): threadid=37: thread exiting with uncaught exception (group=0x40de0930)
06-13 11:07:46.757 I/ActivityManager(29427): Start proc com.android.defcontainer for service com.android.defcontainer/.DefaultContainerService: pid=31476 uid=10011 gids={50011, 1015, 2001, 1028}
06-13 11:07:46.910 D/AudioHardware(29209): AudioHardware pcm playback is going to standby.
06-13 11:07:46.910 V/AudioHardware(29209): closeMixer_l() mMixerOpenCnt: 1
06-13 11:07:46.910 D/AudioHardware(29209): closePcmOut_l() mPcmOpenCnt: 1
06-13 11:07:46.910 E/AndroidRuntime(29427): *** FATAL EXCEPTION IN SYSTEM PROCESS: NetworkStats
06-13 11:07:46.910 E/AndroidRuntime(29427): java.lang.RuntimeException: Error receiving broadcast Intent { act=android.intent.action.UID_REMOVED flg=0x8000010 (has extras) } in [email protected]
06-13 11:07:46.910 E/AndroidRuntime(29427): at android.app.LoadedApk$ReceiverDispatcher$Args.run(LoadedApk.java:768)
06-13 11:07:46.910 E/AndroidRuntime(29427): at android.os.Handler.handleCallback(Handler.java:725)
06-13 11:07:46.910 E/AndroidRuntime(29427): at android.os.Handler.dispatchMessage(Handler.java:92)
06-13 11:07:46.910 E/AndroidRuntime(29427): at android.os.Looper.loop(Looper.java:137)
06-13 11:07:46.910 E/AndroidRuntime(29427): at android.os.HandlerThread.run(HandlerThread.java:60)
06-13 11:07:46.910 E/AndroidRuntime(29427): Caused by: java.lang.IllegalArgumentException: tried recording negative data
06-13 11:07:46.910 E/AndroidRuntime(29427): at android.net.NetworkStatsHistory.recordData(NetworkStatsHistory.java:302)
06-13 11:07:46.910 E/AndroidRuntime(29427): at android.net.NetworkStatsHistory.recordHistory(NetworkStatsHistory.java:375)
06-13 11:07:46.910 E/AndroidRuntime(29427): at android.net.NetworkStatsHistory.recordEntireHistory(NetworkStatsHistory.java:351)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.server.net.NetworkStatsCollection.recordHistory(NetworkStatsCollection.java:220)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.server.net.NetworkStatsCollection.read(NetworkStatsCollection.java:282)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.server.net.NetworkStatsCollection.read(NetworkStatsCollection.java:256)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.server.net.NetworkStatsRecorder$RemoveUidRewriter.read(NetworkStatsRecorder.java:315)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.internal.util.FileRotator.readFile(FileRotator.java:367)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.internal.util.FileRotator.rewriteSingle(FileRotator.java:242)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.internal.util.FileRotator.rewriteAll(FileRotator.java:223)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.server.net.NetworkStatsRecorder.removeUidsLocked(NetworkStatsRecorder.java:240)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.server.net.NetworkStatsService.removeUidsLocked(NetworkStatsService.java:1077)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.server.net.NetworkStatsService.access$1000(NetworkStatsService.java:139)
06-13 11:07:46.910 E/AndroidRuntime(29427): at com.android.server.net.NetworkStatsService$5.onReceive(NetworkStatsService.java:760)
06-13 11:07:46.910 E/AndroidRuntime(29427): at android.app.LoadedApk$ReceiverDispatcher$Args.run(LoadedApk.java:758)
06-13 11:07:46.910 E/AndroidRuntime(29427): ... 4 more
06-13 11:07:47.250 I/Process (29427): Sending signal. PID: 29427 SIG: 9
06-13 11:07:47.277 I/ServiceManager( 132): service 'profile' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'notification' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'devicestoragemonitor' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'location' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'country_detector' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'search' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'dropbox' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'vibrator' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'serial' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'uimode' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'servicediscovery' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'throttle' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'fm_receiver' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'backup' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'appwidget' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'diskstats' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'samplingprofiler' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'commontime_management' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'dreams' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'assetredirection' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'pieservice' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'usb' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'connectivity' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'entropy' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'batteryinfo' died
06-13 11:07:47.277 I/ServiceManager( 132): service 'gfxinfo' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'input' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'accessibility' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'lock_settings' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'clipboard' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'battery' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'window' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'fm_transmitter' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'wallpaper' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'sensorservice' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'account' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'permission' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'meminfo' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'wifi' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'alarm' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'package' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'scheduling_policy' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'display' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'telephony.registry' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'hardware' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'mount' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'user' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'cpuinfo' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'bluetooth_manager' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'dbinfo' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'updatelock' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'power' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'content' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'activity' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'usagestats' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'device_policy' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'netpolicy' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'statusbar' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'textservices' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'audio' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'input_method' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'wifip2p' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'netstats' died
06-13 11:07:47.281 I/ServiceManager( 132): service 'network_management' died
06-13 11:07:47.285 W/AudioFlinger(29209): power manager service died !!!
06-13 11:07:47.285 W/Sensors (30047): sensorservice died [0x52bacc20]
06-13 11:07:47.285 W/Sensors (29783): sensorservice died [0x523f1568]
06-13 11:07:47.285 W/Sensors (29669): sensorservice died [0x4ee95a30]
06-13 11:07:47.293 D/SurfaceFlinger( 138): Screen acquired, type=0 flinger=0x40c6a318
06-13 11:07:47.293 D/SurfaceFlinger( 138): screen was previously acquired
06-13 11:07:47.316 E/BufferQueue( 138): [com.android.packageinstaller/com.android.packageinstaller.UninstallAppProgress] queueBuffer: SurfaceTexture has been abandoned!
06-13 11:07:47.316 E/SurfaceTextureClient(31430): queueBuffer: error queuing buffer to SurfaceTexture, -19
06-13 11:07:47.320 W/HardwareRenderer(31430): EGL error: EGL_BAD_NATIVE_WINDOW
06-13 11:07:47.328 W/BufferQueue( 138): [com.android.packageinstaller/com.android.packageinstaller.UninstallAppProgress] cancelBuffer: BufferQueue has been abandoned!
06-13 11:07:47.347 W/HardwareRenderer(31430): Mountain View, we've had a problem here. Switching back to software rendering.
06-13 11:07:47.351 E/BufferQueue( 138): [com.android.packageinstaller/com.android.packageinstaller.UninstallAppProgress] connect: BufferQueue has been abandoned!
06-13 11:07:47.421 E/installd( 142): eof
06-13 11:07:47.421 E/installd( 142): failed to read size
06-13 11:07:47.421 I/installd( 142): closing connection
06-13 11:07:47.476 W/dalvikvm(31200): threadid=11: thread exiting with uncaught exception (group=0x40de0930)
06-13 11:07:47.492 E/InputEventReceiver(29538): channel '4117b4b8 StatusBar (client)' ~ Publisher closed input channel or an error occurred. events=0x9
06-13 11:07:47.492 E/InputEventReceiver(29538): channel '4160f8c8 com.android.systemui.ImageWallpaper (client)' ~ Publisher closed input channel or an error occurred. events=0x9
06-13 11:07:47.511 I/Zygote (29211): Exit zygote because system server (29427) has terminated
06-13 11:07:47.539 I/ServiceManager( 132): service 'media.audio_policy' died
06-13 11:07:47.539 I/ServiceManager( 132): service 'media.audio_flinger' died
06-13 11:07:47.539 I/ServiceManager( 132): service 'media.player' died
06-13 11:07:47.539 I/ServiceManager( 132): service 'media.camera' died
06-13 11:07:47.742 I/Netd (31493): Netd 1.0 starting
06-13 11:07:47.746 W/InterfaceController(31493): Warning (Cannot load library: load_library(linker.cpp:771): library "/system/lib/libnetcmdiface.so" not found) while opening the net interface command library
06-13 11:07:47.757 I/ServiceManager( 132): service 'phone' died
06-13 11:07:47.757 I/ServiceManager( 132): service 'sip' died
06-13 11:07:47.757 I/ServiceManager( 132): service 'isms' died
06-13 11:07:47.757 I/ServiceManager( 132): service 'iphonesubinfo' died
06-13 11:07:47.757 I/ServiceManager( 132): service 'simphonebook' died
06-13 11:07:47.972 D/libEGL (31491): loaded /vendor/lib/egl/libEGL_POWERVR_SGX540_120.so
06-13 11:07:47.996 D/libEGL (31491): loaded /vendor/lib/egl/libGLESv1_CM_POWERVR_SGX540_120.so
06-13 11:07:48.007 D/libEGL (31491): loaded /vendor/lib/egl/libGLESv2_POWERVR_SGX540_120.so
06-13 11:07:48.363 D/AndroidRuntime(31494):
06-13 11:07:48.363 D/AndroidRuntime(31494): >>>>>> AndroidRuntime START com.android.internal.os.ZygoteInit <<<<<<
06-13 11:07:48.363 D/AndroidRuntime(31494): CheckJNI is OFF

I had a backup from 3 days ago which I've just restored. I have deleted Dalvik cache and formatted cache partition as well.
Gapps stopped working (probably because of cache delete), reporting errors every few seconds.
I have updated the system again to the latest and installed latest Gapps again.
I'm trying to avoid going through factory reset again. Will report if this solved the problem.

Yankovic said:
A full wipe and fresh install is a bit extreme in my eyes when I don't know what is really causing this issue.. I've been looking a bit deeper and saw that the network usage in the settings would also crash everytime i tried to open it (with a similar stacktrace).
Maybe it saved some invalid value to the netstats (as the stacktrace mentions IllegalArgumentException: tried recording negative data) at some time so I removed all the files from /data/system/netstats directory and now it seems to work again
Click to expand...
Click to collapse
Found this, years later, after already wiping my phone, looking for solutions. Gah, I feel dumb lol.

[Completed] Nexus 5: Error enabling Bluetooth - hardware init failed

Hi folks
I used my Nexus 5 nearly 2 years with full of happiness. But recently I've noticed that my bluetooth stopped working. It happened after Android M upgrade to be clear. First I thought OS was the problem. And bluetooth was not so essential for me and i ignored the problem. Now I've new LG Urbane and it needs to be connected to smartphone. Now after few times full resetting, even after flashing custom rom and stock android lollipop i had no luck.Now bluetooth keeps enabling and disabling randomly after 2-3 hours. But no luck with enable-disable button. I've checked permissions on vendor/lib/libbt-vendor.so it is rw-r--r--
I am posting log file, i've googled a lot about following problem, there are a lot of same cases but no answers. Please all mighty developers help me!
08-19 23:03:29.607: D/BluetoothManagerService(782): checkIfCallerIsForegroundUser: valid=true callingUser=0 parentUser=-10000 foregroundUser=0
08-19 23:03:29.608: D/BluetoothManagerService(782): enable(): mBluetooth =null mBinding = false
08-19 23:03:29.608: D/BluetoothManagerService(782): Message: 1
08-19 23:03:29.608: D/BluetoothManagerService(782): MESSAGE_ENABLE: mBluetooth = null
08-19 23:03:29.612: D/audio_hw_primary(189): enable_audio_route: apply and update mixer path: low-latency-playback
08-19 23:03:29.622: E/MediaPlayer(8756): Should have subtitle controller already set
08-19 23:03:29.633: I/ActivityManager(782): Start proc 11774:com.android.bluetooth/1002 for service com.android.bluetooth/.btservice.AdapterService
08-19 23:03:29.672: W/ResourcesManager(11774): Asset path '/system/framework/javax.obex.jar' does not exist or contains no resources.
08-19 23:03:29.711: D/AdapterServiceConfig(11774): Adding HeadsetService
08-19 23:03:29.711: D/AdapterServiceConfig(11774): Adding A2dpService
08-19 23:03:29.711: D/AdapterServiceConfig(11774): Adding HidService
08-19 23:03:29.711: D/AdapterServiceConfig(11774): Adding HealthService
08-19 23:03:29.712: D/AdapterServiceConfig(11774): Adding PanService
08-19 23:03:29.712: D/AdapterServiceConfig(11774): Adding GattService
08-19 23:03:29.712: D/AdapterServiceConfig(11774): Adding BluetoothMapService
08-19 23:03:29.728: D/BluetoothManagerService(782): Message: 20
08-19 23:03:29.728: D/BluetoothManagerService(782): Added callback: [email protected]:true
08-19 23:03:29.731: D/BluetoothAdapterState(11774): make
08-19 23:03:29.733: I/bluedroid(11774): init
08-19 23:03:29.734: I/BluetoothAdapterState(11774): Entering OffState
08-19 23:03:29.734: I/bte_conf(11774): bte_load_conf attempt to load stack conf from /etc/bluetooth/bt_stack.conf
08-19 23:03:29.734: I/bte_conf(11774): bte_load_ble_conf attempt to load ble stack conf from /etc/bluetooth/ble_stack.conf
08-19 23:03:29.734: E/bt_osi_config(11774): config_new unable to open file '/etc/bluetooth/ble_stack.conf': No such file or directory
08-19 23:03:29.734: I/bte_conf(11774): bte_load_ble_conf file >/etc/bluetooth/ble_stack.conf< not found
08-19 23:03:29.734: I/bluedroid(11774): get_profile_interface socket
08-19 23:03:29.734: I/bluedroid(11774): get_profile_interface map_client
08-19 23:03:29.735: I/GKI_LINUX(11774): gki_task_entry task_id=1 [BTIF] starting
08-19 23:03:29.737: D/BluetoothManagerService(782): BluetoothServiceConnection: com.android.bluetooth.btservice.AdapterService
08-19 23:03:29.737: D/BluetoothManagerService(782): Message: 40
08-19 23:03:29.737: D/BluetoothManagerService(782): MESSAGE_BLUETOOTH_SERVICE_CONNECTED: 1
08-19 23:03:29.737: I/bluedroid(11774): config_hci_snoop_log
08-19 23:03:29.738: D/BluetoothManagerService(782): Calling onBluetoothServiceUp callbacks
08-19 23:03:29.738: D/BluetoothManagerService(782): Broadcasting onBluetoothServiceUp() to 13 receivers.
08-19 23:03:29.746: E/WifiStateMachine(782): WifiStateMachine CMD_START_SCAN source -2 txSuccessRate=0.00 rxSuccessRate=0.00 targetRoamBSSID=c0:4a:00:fa:89:09 RSSI=-37
08-19 23:03:29.746: E/WifiStateMachine(782): WifiStateMachine starting scan for "Kamranno"WPA_PSK with 2422
08-19 23:03:29.747: D/BluetoothAdapterProperties(11774): Address is:BC:F5:AC:50:37:16
08-19 23:03:29.748: D/BluetoothAdapterProperties(11774): Name is: Nexus 5
08-19 23:03:29.748: D/BluetoothManagerService(782): Bluetooth Adapter name changed to Nexus 5
08-19 23:03:29.748: D/BluetoothManagerService(782): Stored Bluetooth name: Nexus 5
08-19 23:03:29.755: D/BluetoothAdapterState(11774): CURRENT_STATE=OFF, MESSAGE = USER_TURN_ON
08-19 23:03:29.755: D/BluetoothAdapterProperties(11774): Setting state to 11
08-19 23:03:29.755: I/BluetoothAdapterState(11774): Bluetooth adapter state changed: 10-> 11
08-19 23:03:29.757: D/BluetoothManagerService(782): Message: 60
08-19 23:03:29.757: D/BluetoothManagerService(782): MESSAGE_BLUETOOTH_STATE_CHANGE: prevState = 10, newState=11
08-19 23:03:29.757: D/BluetoothManagerService(782): Bluetooth State Change Intent: 10 -> 11
08-19 23:03:29.763: D/BluetoothBondStateMachine(11774): make
08-19 23:03:29.771: I/BluetoothBondStateMachine(11774): StableState(): Entering Off State
08-19 23:03:29.775: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected]
08-19 23:03:29.778: D/HeadsetService(11774): Received start request. Starting profile...
08-19 23:03:29.780: I/BluetoothHeadsetServiceJni(11774): classInitNative: succeeds
08-19 23:03:29.781: D/HeadsetStateMachine(11774): make
08-19 23:03:29.792: D/HeadsetStateMachine(11774): max_hf_connections = 1
08-19 23:03:29.792: I/bluedroid(11774): get_profile_interface handsfree
08-19 23:03:29.793: D/HeadsetStateMachine(11774): Enter Disconnected: -2, size: 0
08-19 23:03:29.795: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected]
08-19 23:03:29.795: D/A2dpService(11774): Received start request. Starting profile...
08-19 23:03:29.795: I/BluetoothAvrcpServiceJni(11774): classInitNative: succeeds
08-19 23:03:29.795: I/bluedroid(11774): get_profile_interface avrcp
08-19 23:03:29.798: I/BluetoothAdapterState(11774): Entering PendingCommandState State: isTurningOn()=true, isTurningOff()=false
08-19 23:03:29.801: E/RemoteController(11774): Cannot set synchronization mode on an unregistered RemoteController
08-19 23:03:29.801: I/BluetoothA2dpServiceJni(11774): classInitNative: succeeds
08-19 23:03:29.801: D/A2dpStateMachine(11774): make
08-19 23:03:29.802: I/bluedroid(11774): get_profile_interface a2dp
08-19 23:03:29.802: I/GKI_LINUX(11774): gki_task_entry task_id=2 [A2DP-MEDIA] starting
08-19 23:03:29.804: D/A2dpStateMachine(11774): Enter Disconnected: -2
08-19 23:03:29.804: I/BluetoothHidServiceJni(11774): classInitNative: succeeds
08-19 23:03:29.805: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected]
08-19 23:03:29.805: D/HidService(11774): Received start request. Starting profile...
08-19 23:03:29.805: I/bluedroid(11774): get_profile_interface hidhost
08-19 23:03:29.806: I/BluetoothHealthServiceJni(11774): classInitNative: succeeds
08-19 23:03:29.807: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected]
08-19 23:03:29.810: D/HealthService(11774): Received start request. Starting profile...
08-19 23:03:29.812: I/bluedroid(11774): get_profile_interface health
08-19 23:03:29.817: D/HeadsetStateMachine(11774): Proxy object connected
08-19 23:03:29.817: I/BluetoothPanServiceJni(11774): classInitNative(L105): succeeds
08-19 23:03:29.818: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected]
08-19 23:03:29.818: D/PanService(11774): Received start request. Starting profile...
08-19 23:03:29.818: D/BluetoothPanServiceJni(11774): initializeNative(L110): pan
08-19 23:03:29.818: I/bluedroid(11774): get_profile_interface pan
08-19 23:03:29.820: D/HeadsetStateMachine(11774): Disconnected process message: 10, size: 0
08-19 23:03:29.820: D/HeadsetPhoneState(11774): sendDeviceStateChanged. mService=0 mSignal=0 mRoam=0 mBatteryCharge=4
08-19 23:03:29.820: D/HeadsetStateMachine(11774): Disconnected process message: 11, size: 0
08-19 23:03:29.821: I/BtGatt.JNI(11774): classInitNative(L873): classInitNative: Success!
08-19 23:03:29.822: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected]
08-19 23:03:29.824: D/BtGatt.DebugUtils(11774): handleDebugAction() action=null
08-19 23:03:29.824: D/BtGatt.GattService(11774): Received start request. Starting profile...
08-19 23:03:29.824: D/BtGatt.GattService(11774): start()
08-19 23:03:29.824: I/bluedroid(11774): get_profile_interface gatt
08-19 23:03:29.825: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected]
08-19 23:03:29.825: D/BtGatt.AdvertiseManager(11774): advertise manager created
08-19 23:03:29.830: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected]
08-19 23:03:29.830: D/BluetoothMapService(11774): Received start request. Starting profile...
08-19 23:03:29.830: D/BluetoothMapService(11774): start()
08-19 23:03:29.832: D/BluetoothMapEmailSettingsLoader(11774): Found 0 applications
08-19 23:03:29.832: D/BluetoothMapEmailAppObserver(11774): createReceiver()
08-19 23:03:29.833: D/BluetoothMapEmailAppObserver(11774): initObservers()
08-19 23:03:29.833: D/BluetoothMapEmailAppObserver(11774): getEnabledAccountItems()
08-19 23:03:29.838: D/HeadsetPhoneState(11774): sendDeviceStateChanged. mService=1 mSignal=0 mRoam=0 mBatteryCharge=4
08-19 23:03:29.838: D/HeadsetStateMachine(11774): Disconnected process message: 11, size: 0
08-19 23:03:29.838: D/HeadsetPhoneState(11774): sendDeviceStateChanged. mService=1 mSignal=4 mRoam=0 mBatteryCharge=4
08-19 23:03:29.838: D/HeadsetStateMachine(11774): Disconnected process message: 11, size: 0
08-19 23:03:29.839: D/BluetoothAdapterState(11774): CURRENT_STATE=PENDING, MESSAGE = STARTED, isTurningOn=true, isTurningOff=false
08-19 23:03:29.839: I/bluedroid(11774): enable
08-19 23:03:29.839: I/GKI_LINUX(11774): gki_task_entry task_id=0 [BTU] starting
08-19 23:03:29.839: I/bt-btu(11774): btu_task pending for preload complete event
08-19 23:03:29.839: I/bt_hci_bdroid(11774): init
08-19 23:03:29.840: D/AuthorizationBluetoothService(7627): Received Bluetooth event: Intent { act=android.bluetooth.adapter.action.STATE_CHANGED flg=0x4000010 cmp=com.google.android.gms/.auth.be.proximity.authorization.bt.AuthorizationBluetoothService$BluetoothStateChangeReceiver (has extras) }.
08-19 23:03:29.842: I/bt_vendor(11774): init
08-19 23:03:29.842: I/bt_vnd_conf(11774): Attempt to load conf from /etc/bluetooth/bt_vendor.conf
08-19 23:03:29.842: I/bt_vnd_conf(11774): vnd_load_conf file >/etc/bluetooth/bt_vendor.conf< not found
08-19 23:03:29.842: D/bt_userial(11774): userial_init
08-19 23:03:29.879: I/bt_userial_vendor(11774): userial vendor open: opening /dev/ttyHS99
08-19 23:03:29.883: I/bt_userial_vendor(11774): device fd = 52 open
08-19 23:03:29.883: D/bt_userial(11774): Entering userial_read_thread()
08-19 23:03:29.937: D/HeadsetStateMachine(11774): Disconnected process message: 10, size: 0
08-19 23:03:30.168: D/HeadsetStateMachine(11774): Disconnected process message: 10, size: 0
08-19 23:03:32.801: D/audio_hw_primary(189): disable_audio_route: reset and update mixer path: low-latency-playback
08-19 23:03:32.801: D/audio_hw_primary(189): disable_snd_device: snd_device(2: speaker)
08-19 23:03:32.840: E/bt-btif(11774): ...preload_wait_timeout (retried:0/max-retry:0)...
08-19 23:03:32.842: D/bt_userial(11774): RX termination
08-19 23:03:32.842: W/bt_userial(11774): select_read return size <=0:-1, exiting userial_read_thread
08-19 23:03:32.842: D/bt_userial(11774): Leaving userial_read_thread()
08-19 23:03:32.842: I/bt_userial_vendor(11774): device fd = 52 close
08-19 23:03:32.947: W/bt-btu(11774): btu_task start abort!
08-19 23:03:32.948: I/GKI_LINUX(11774): gki_task task_id=0 [BTU] terminating
08-19 23:03:32.948: I/GKI_LINUX(11774): GKI_exit_task 0 done
08-19 23:03:32.948: I/GKI_LINUX(11774): GKI_shutdown(): task [BTU] terminated
08-19 23:03:32.948: I/GKI_LINUX(11774): GKI_exit_task 1 done
08-19 23:03:32.949: I/GKI_LINUX(11774): gki_task task_id=2 [A2DP-MEDIA] terminating
08-19 23:03:32.949: I/GKI_LINUX(11774): GKI_exit_task 2 done
08-19 23:03:32.954: D/BluetoothAdapterState(11774): CURRENT_STATE=PENDING, MESSAGE = DISABLED, isTurningOn=true, isTurningOff=false
08-19 23:03:32.954: E/BluetoothAdapterState(11774): Error enabling Bluetooth - hardware init failed
08-19 23:03:32.955: I/GKI_LINUX(11774): gki_task task_id=1 [BTIF] terminating
08-19 23:03:32.962: D/HeadsetService(11774): Received stop request...Stopping profile...
08-19 23:03:32.964: D/HeadsetStateMachine(11774): Exit Disconnected: -1
08-19 23:03:32.970: D/BluetoothAdapterProperties(11774): Setting state to 10
08-19 23:03:32.971: I/BluetoothAdapterState(11774): Bluetooth adapter state changed: 11-> 10
08-19 23:03:32.971: D/A2dpService(11774): Received stop request...Stopping profile...
08-19 23:03:32.971: D/BluetoothManagerService(782): Message: 60
08-19 23:03:32.971: D/BluetoothManagerService(782): MESSAGE_BLUETOOTH_STATE_CHANGE: prevState = 11, newState=10
08-19 23:03:32.971: D/BluetoothManagerService(782): Broadcasting onBluetoothStateChange(false) to 17 receivers.
08-19 23:03:32.972: D/A2dpStateMachine(11774): Exit Disconnected: -1
08-19 23:03:32.972: D/BluetoothA2dpSink(929): onBluetoothStateChange: up=false
08-19 23:03:32.973: E/BluetoothA2dpSink(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.bluetooth.BluetoothA2dpSink$1.onBluetoothStateChange(BluetoothA2dpSink.java:139)
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.973: I/BluetoothAdapterState(11774): Entering OffState
08-19 23:03:32.974: D/BluetoothInputDevice(3298): onBluetoothStateChange: up=false
08-19 23:03:32.974: E/BluetoothInputDevice(3298): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.bluetooth.BluetoothInputDevice$1.onBluetoothStateChange(BluetoothInputDevice.java:216)
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.975: D/HidService(11774): Received stop request...Stopping profile...
08-19 23:03:32.976: E/BluetoothPan(3298): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.976: E/BluetoothPan(3298): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.976: E/BluetoothPan(3298): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.976: E/BluetoothPan(3298): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.976: E/BluetoothPan(3298): at android.bluetooth.BluetoothPan$1.onBluetoothStateChange(BluetoothPan.java:199)
08-19 23:03:32.976: E/BluetoothPan(3298): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.976: E/BluetoothPan(3298): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.976: D/BluetoothMap(3298): onBluetoothStateChange: up=false
08-19 23:03:32.977: E/BluetoothMap(3298): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.977: E/BluetoothMap(3298): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.977: E/BluetoothMap(3298): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.977: E/BluetoothMap(3298): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.977: E/BluetoothMap(3298): at android.bluetooth.BluetoothMap$1.onBluetoothStateChange(BluetoothMap.java:64)
08-19 23:03:32.977: E/BluetoothMap(3298): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.977: E/BluetoothMap(3298): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.977: D/BluetoothA2dp(782): onBluetoothStateChange: up=false
08-19 23:03:32.977: D/HealthService(11774): Received stop request...Stopping profile...
08-19 23:03:32.978: E/BluetoothA2dp(782): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.bluetooth.BluetoothA2dp$1.onBluetoothStateChange(BluetoothA2dp.java:126)
08-19 23:03:32.978: E/BluetoothA2dp(782): at com.android.server.BluetoothManagerService.sendBluetoothStateCallback(BluetoothManagerService.java:710)
08-19 23:03:32.978: E/BluetoothA2dp(782): at com.android.server.BluetoothManagerService.bluetoothStateChangeHandler(BluetoothManagerService.java:1386)
08-19 23:03:32.978: E/BluetoothA2dp(782): at com.android.server.BluetoothManagerService.access$3300(BluetoothManagerService.java:61)
08-19 23:03:32.978: E/BluetoothA2dp(782): at com.android.server.BluetoothManagerService$BluetoothHandler.handleMessage(BluetoothManagerService.java:1113)
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.os.Handler.dispatchMessage(Handler.java:102)
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.os.Looper.loop(Looper.java:135)
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.os.HandlerThread.run(HandlerThread.java:61)
08-19 23:03:32.978: E/BluetoothA2dp(782): at com.android.server.ServiceThread.run(ServiceThread.java:46)
08-19 23:03:32.978: D/BluetoothHeadset(1198): onBluetoothStateChange: up=false
08-19 23:03:32.979: D/PanService(11774): Received stop request...Stopping profile...
08-19 23:03:32.979: D/BluetoothInputDevice(929): onBluetoothStateChange: up=false
08-19 23:03:32.979: E/BluetoothInputDevice(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.bluetooth.BluetoothInputDevice$1.onBluetoothStateChange(BluetoothInputDevice.java:216)
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.979: D/BluetoothHeadset(782): onBluetoothStateChange: up=false
08-19 23:03:32.980: D/BluetoothHeadset(929): onBluetoothStateChange: up=false
08-19 23:03:32.980: D/HeadsetStateMachine(11774): Unbinding service...
08-19 23:03:32.981: D/BluetoothHeadset(782): onBluetoothStateChange: up=false
08-19 23:03:32.982: E/BluetoothPan(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.982: E/BluetoothPan(929): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.982: E/BluetoothPan(929): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.982: E/BluetoothPan(929): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.982: E/BluetoothPan(929): at android.bluetooth.BluetoothPan$1.onBluetoothStateChange(BluetoothPan.java:199)
08-19 23:03:32.982: E/BluetoothPan(929): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.982: E/BluetoothPan(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.985: D/BluetoothPbap(3298): onBluetoothStateChange: up=false
08-19 23:03:32.985: W/BluetoothHeadsetServiceJni(11774): Cleaning up Bluetooth Handsfree Interface...
08-19 23:03:32.985: W/BluetoothHeadsetServiceJni(11774): Cleaning up Bluetooth Handsfree callback object
08-19 23:03:32.985: D/BtGatt.DebugUtils(11774): handleDebugAction() action=null
08-19 23:03:32.985: D/BtGatt.GattService(11774): Received stop request...Stopping profile...
08-19 23:03:32.985: D/BtGatt.GattService(11774): stop()
08-19 23:03:32.985: D/BtGatt.AdvertiseManager(11774): advertise clients cleared
08-19 23:03:32.986: E/BluetoothPbap(3298): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.bluetooth.BluetoothPbap$1.onBluetoothStateChange(BluetoothPbap.java:121)
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.986: D/BluetoothHeadsetClient(929): onBluetoothStateChange: up=false
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.bluetooth.BluetoothHeadsetClient$1.onBluetoothStateChange(BluetoothHeadsetClient.java:382)
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.988: D/BluetoothMapService(11774): Received stop request...Stopping profile...
08-19 23:03:32.988: D/BluetoothMapService(11774): stop()
08-19 23:03:32.988: D/BluetoothAvrcpController(929): onBluetoothStateChange: up=false
08-19 23:03:32.988: D/BluetoothMapEmailAppObserver(11774): deinitObservers()
08-19 23:03:32.989: D/BluetoothMapEmailAppObserver(11774): removeReceiver()
08-19 23:03:32.989: E/BluetoothAvrcpController(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.bluetooth.BluetoothAvrcpController$1.onBluetoothStateChange(BluetoothAvrcpController.java:80)
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.989: D/BluetoothHeadset(782): onBluetoothStateChange: up=false
08-19 23:03:32.989: W/BluetoothHidServiceJni(11774): Cleaning up Bluetooth HID Interface...
08-19 23:03:32.989: W/bt-btif(11774): cleanup: HH disabling or disabled already, status = 0
08-19 23:03:32.989: W/BluetoothHidServiceJni(11774): Cleaning up Bluetooth GID callback object
08-19 23:03:32.990: W/BluetoothHealthServiceJni(11774): Cleaning up Bluetooth Health Interface...
08-19 23:03:32.990: W/BluetoothHealthServiceJni(11774): Cleaning up Bluetooth Health object
08-19 23:03:32.990: W/BluetoothPanServiceJni(11774): Cleaning up Bluetooth PAN Interface...
08-19 23:03:32.990: W/BluetoothPanServiceJni(11774): Cleaning up Bluetooth PAN callback object
08-19 23:03:32.991: D/BluetoothA2dp(929): onBluetoothStateChange: up=false
08-19 23:03:32.991: D/BtGatt.GattService(11774): cleanup()
08-19 23:03:32.991: W/bt-btif(11774): GATTC Module not enabled/already disabled
08-19 23:03:32.991: W/bt-btif(11774): GATTS Module not enabled/already disabled
08-19 23:03:32.991: D/BtGatt.AdvertiseManager(11774): advertise clients cleared
08-19 23:03:32.991: D/BluetoothMapService(11774): MAP Service closeService in
08-19 23:03:32.991: E/BluetoothA2dp(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.bluetooth.BluetoothA2dp$1.onBluetoothStateChange(BluetoothA2dp.java:126)
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.991: D/BluetoothMapMasInstance(11774): MAP Service shutdown
08-19 23:03:32.991: D/BluetoothMapService(11774): cleanup()
08-19 23:03:32.991: D/BluetoothMapService(11774): MAP Service closeService in
08-19 23:03:32.992: D/BluetoothMap(929): onBluetoothStateChange: up=false
08-19 23:03:32.992: E/BluetoothMap(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.992: E/BluetoothMap(929): at android.app.LoadedApk.forgetServiceDispatcher(LoadedApk.java:1029)
08-19 23:03:32.992: E/BluetoothMap(929): at android.app.ContextImpl.unbindService(ContextImpl.java:1808)
08-19 23:03:32.992: E/BluetoothMap(929): at android.content.ContextWrapper.unbindService(ContextWrapper.java:551)
08-19 23:03:32.992: E/BluetoothMap(929): at android.bluetooth.BluetoothMap$1.onBluetoothStateChange(BluetoothMap.java:64)
08-19 23:03:32.992: E/BluetoothMap(929): at android.bluetooth.IBluetoothStateChangeCallback$Stub.onTransact(IBluetoothStateChangeCallback.java:55)
08-19 23:03:32.992: E/BluetoothMap(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.992: D/BluetoothManagerService(782): Bluetooth State Change Intent: 11 -> 10
08-19 23:03:32.997: E/BluetoothManagerService(782): recoverBluetoothServiceFromError
08-19 23:03:33.000: W/ContextImpl(3298): Calling a method in the system process without a qualified user: android.app.ContextImpl.startService:1692 android.content.ContextWrapper.startService:516 android.content.ContextWrapper.startService:516 com.android.settings.bluetooth.DockEventReceiver.beginStartingService:134 com.android.settings.bluetooth.DockEventReceiver.onReceive:115
08-19 23:03:33.020: D/DockEventReceiver(3298): finishStartingService: stopping service
08-19 23:03:33.037: D/AuthorizationBluetoothService(7627): Received Bluetooth event: Intent { act=android.bluetooth.adapter.action.STATE_CHANGED flg=0x4000010 cmp=com.google.android.gms/.auth.be.proximity.authorization.bt.AuthorizationBluetoothService$BluetoothStateChangeReceiver (has extras) }.
: E/(): Device disconnected

wilkens said:
Hi folks
I used my Nexus 5 nearly 2 years with full of happiness. But recently I've noticed that my bluetooth stopped working. It happened after Android M upgrade to be clear. First I thought OS was the problem. And bluetooth was not so essential for me and i ignored the problem. Now I've new LG Urbane and it needs to be connected to smartphone. Now after few times full resetting, even after flashing custom rom and stock android lollipop i had no luck.Now bluetooth keeps enabling and disabling randomly after 2-3 hours. But no luck with enable-disable button. I've checked permissions on vendor/lib/libbt-vendor.so it is rw-r--r--
I am posting log file, i've googled a lot about following problem, there are a lot of same cases but no answers. Please all mighty developers help me!
...
Click to expand...
Click to collapse
Hello and thank you for using XDA Assist.
Sorry to hear about your trouble but fortunately there is an XDA area dedicated to the Google Nexus 5 at http://forum.xda-developers.com/google-nexus-5 . I suggest you post your question with all relevant details in the friendly Q&A forum there at http://forum.xda-developers.com/google-nexus-5/help where the experts familiar with your device will be best able to guide you.
Good Luck!

Nexus 5: Error enabling Bluetooth - hardware init failed

Hi folks
I used my Nexus 5 nearly 2 years with full of happiness. But recently I've noticed that my bluetooth stopped working. It happened after Android M upgrade to be clear. First I thought OS was the problem. And bluetooth was not so essential for me and i ignored the problem. Now I've new LG Urbane and it needs to be connected to smartphone. Now after few times full resetting, even after flashing custom rom and stock android lollipop i had no luck.Now bluetooth keeps enabling and disabling randomly after 2-3 hours. But no luck with enable-disable button. I've checked permissions on vendor/lib/libbt-vendor.so it is rw-r--r--
I am posting log file, i've googled a lot about following problem, there are a lot of same cases but no answers. Please all mighty developers help me!
08-19 23:03:29.607: D/BluetoothManagerService(782): checkIfCallerIsForegroundUser: valid=true callingUser=0 parentUser=-10000 foregroundUser=0
08-19 23:03:29.608: D/BluetoothManagerService(782): enable(): mBluetooth =null mBinding = false
08-19 23:03:29.608: D/BluetoothManagerService(782): Message: 1
08-19 23:03:29.608: D/BluetoothManagerService(782): MESSAGE_ENABLE: mBluetooth = null
08-19 23:03:29.612: D/audio_hw_primary(189): enable_audio_route: apply and update mixer path: low-latency-playback
08-19 23:03:29.622: E/MediaPlayer(8756): Should have subtitle controller already set
08-19 23:03:29.633: I/ActivityManager(782): Start proc 11774:com.android.bluetooth/1002 for service com.android.bluetooth/.btservice.AdapterService
08-19 23:03:29.672: W/ResourcesManager(11774): Asset path '/system/framework/javax.obex.jar' does not exist or contains no resources.
08-19 23:03:29.711: D/AdapterServiceConfig(11774): Adding HeadsetService
08-19 23:03:29.711: D/AdapterServiceConfig(11774): Adding A2dpService
08-19 23:03:29.711: D/AdapterServiceConfig(11774): Adding HidService
08-19 23:03:29.711: D/AdapterServiceConfig(11774): Adding HealthService
08-19 23:03:29.712: D/AdapterServiceConfig(11774): Adding PanService
08-19 23:03:29.712: D/AdapterServiceConfig(11774): Adding GattService
08-19 23:03:29.712: D/AdapterServiceConfig(11774): Adding BluetoothMapService
08-19 23:03:29.728: D/BluetoothManagerService(782): Message: 20
08-19 23:03:29.728: D/BluetoothManagerService(782): Added callback: android.bluetooth.IBluetoothManagerCallback$Stub$P [email protected]:true
08-19 23:03:29.731: D/BluetoothAdapterState(11774): make
08-19 23:03:29.733: I/bluedroid(11774): init
08-19 23:03:29.734: I/BluetoothAdapterState(11774): Entering OffState
08-19 23:03:29.734: I/bte_conf(11774): bte_load_conf attempt to load stack conf from /etc/bluetooth/bt_stack.conf
08-19 23:03:29.734: I/bte_conf(11774): bte_load_ble_conf attempt to load ble stack conf from /etc/bluetooth/ble_stack.conf
08-19 23:03:29.734: E/bt_osi_config(11774): config_new unable to open file '/etc/bluetooth/ble_stack.conf': No such file or directory
08-19 23:03:29.734: I/bte_conf(11774): bte_load_ble_conf file >/etc/bluetooth/ble_stack.conf< not found
08-19 23:03:29.734: I/bluedroid(11774): get_profile_interface socket
08-19 23:03:29.734: I/bluedroid(11774): get_profile_interface map_client
08-19 23:03:29.735: I/GKI_LINUX(11774): gki_task_entry task_id=1 [BTIF] starting
08-19 23:03:29.737: D/BluetoothManagerService(782): BluetoothServiceConnection: com.android.bluetooth.btservice.AdapterService
08-19 23:03:29.737: D/BluetoothManagerService(782): Message: 40
08-19 23:03:29.737: D/BluetoothManagerService(782): MESSAGE_BLUETOOTH_SERVICE_CONNECTED: 1
08-19 23:03:29.737: I/bluedroid(11774): config_hci_snoop_log
08-19 23:03:29.738: D/BluetoothManagerService(782): Calling onBluetoothServiceUp callbacks
08-19 23:03:29.738: D/BluetoothManagerService(782): Broadcasting onBluetoothServiceUp() to 13 receivers.
08-19 23:03:29.746: E/WifiStateMachine(782): WifiStateMachine CMD_START_SCAN source -2 txSuccessRate=0.00 rxSuccessRate=0.00 targetRoamBSSID=c0:4a:00:fa:89:09 RSSI=-37
08-19 23:03:29.746: E/WifiStateMachine(782): WifiStateMachine starting scan for "Kamranno"WPA_PSK with 2422
08-19 23:03:29.747: D/BluetoothAdapterProperties(11774): Address is:BC:F5:AC:50:37:16
08-19 23:03:29.748: D/BluetoothAdapterProperties(11774): Name is: Nexus 5
08-19 23:03:29.748: D/BluetoothManagerService(782): Bluetooth Adapter name changed to Nexus 5
08-19 23:03:29.748: D/BluetoothManagerService(782): Stored Bluetooth name: Nexus 5
08-19 23:03:29.755: D/BluetoothAdapterState(11774): CURRENT_STATE=OFF, MESSAGE = USER_TURN_ON
08-19 23:03:29.755: D/BluetoothAdapterProperties(11774): Setting state to 11
08-19 23:03:29.755: I/BluetoothAdapterState(11774): Bluetooth adapter state changed: 10-> 11
08-19 23:03:29.757: D/BluetoothManagerService(782): Message: 60
08-19 23:03:29.757: D/BluetoothManagerService(782): MESSAGE_BLUETOOTH_STATE_CHANGE: prevState = 10, newState=11
08-19 23:03:29.757: D/BluetoothManagerService(782): Bluetooth State Change Intent: 10 -> 11
08-19 23:03:29.763: D/BluetoothBondStateMachine(11774): make
08-19 23:03:29.771: I/BluetoothBondStateMachine(11774): StableState(): Entering Off State
08-19 23:03:29.775: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected] 723be
08-19 23:03:29.778: D/HeadsetService(11774): Received start request. Starting profile...
08-19 23:03:29.780: I/BluetoothHeadsetServiceJni(11774): classInitNative: succeeds
08-19 23:03:29.781: D/HeadsetStateMachine(11774): make
08-19 23:03:29.792: D/HeadsetStateMachine(11774): max_hf_connections = 1
08-19 23:03:29.792: I/bluedroid(11774): get_profile_interface handsfree
08-19 23:03:29.793: D/HeadsetStateMachine(11774): Enter Disconnected: -2, size: 0
08-19 23:03:29.795: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected] 723be
08-19 23:03:29.795: D/A2dpService(11774): Received start request. Starting profile...
08-19 23:03:29.795: I/BluetoothAvrcpServiceJni(11774): classInitNative: succeeds
08-19 23:03:29.795: I/bluedroid(11774): get_profile_interface avrcp
08-19 23:03:29.798: I/BluetoothAdapterState(11774): Entering PendingCommandState State: isTurningOn()=true, isTurningOff()=false
08-19 23:03:29.801: E/RemoteController(11774): Cannot set synchronization mode on an unregistered RemoteController
08-19 23:03:29.801: I/BluetoothA2dpServiceJni(11774): classInitNative: succeeds
08-19 23:03:29.801: D/A2dpStateMachine(11774): make
08-19 23:03:29.802: I/bluedroid(11774): get_profile_interface a2dp
08-19 23:03:29.802: I/GKI_LINUX(11774): gki_task_entry task_id=2 [A2DP-MEDIA] starting
08-19 23:03:29.804: D/A2dpStateMachine(11774): Enter Disconnected: -2
08-19 23:03:29.804: I/BluetoothHidServiceJni(11774): classInitNative: succeeds
08-19 23:03:29.805: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected] 723be
08-19 23:03:29.805: D/HidService(11774): Received start request. Starting profile...
08-19 23:03:29.805: I/bluedroid(11774): get_profile_interface hidhost
08-19 23:03:29.806: I/BluetoothHealthServiceJni(11774): classInitNative: succeeds
08-19 23:03:29.807: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected] 723be
08-19 23:03:29.810: D/HealthService(11774): Received start request. Starting profile...
08-19 23:03:29.812: I/bluedroid(11774): get_profile_interface health
08-19 23:03:29.817: D/HeadsetStateMachine(11774): Proxy object connected
08-19 23:03:29.817: I/BluetoothPanServiceJni(11774): classInitNative(L105): succeeds
08-19 23:03:29.818: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected] 723be
08-19 23:03:29.818: D/PanService(11774): Received start request. Starting profile...
08-19 23:03:29.818: D/BluetoothPanServiceJni(11774): initializeNative(L110): pan
08-19 23:03:29.818: I/bluedroid(11774): get_profile_interface pan
08-19 23:03:29.820: D/HeadsetStateMachine(11774): Disconnected process message: 10, size: 0
08-19 23:03:29.820: D/HeadsetPhoneState(11774): sendDeviceStateChanged. mService=0 mSignal=0 mRoam=0 mBatteryCharge=4
08-19 23:03:29.820: D/HeadsetStateMachine(11774): Disconnected process message: 11, size: 0
08-19 23:03:29.821: I/BtGatt.JNI(11774): classInitNative(L873): classInitNative: Success!
08-19 23:03:29.822: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected] 723be
08-19 23:03:29.824: D/BtGatt.DebugUtils(11774): handleDebugAction() action=null
08-19 23:03:29.824: D/BtGatt.GattService(11774): Received start request. Starting profile...
08-19 23:03:29.824: D/BtGatt.GattService(11774): start()
08-19 23:03:29.824: I/bluedroid(11774): get_profile_interface gatt
08-19 23:03:29.825: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected] 723be
08-19 23:03:29.825: D/BtGatt.AdvertiseManager(11774): advertise manager created
08-19 23:03:29.830: D/BluetoothAdapterService(11774): getAdapterService() - returning [email protected] 723be
08-19 23:03:29.830: D/BluetoothMapService(11774): Received start request. Starting profile...
08-19 23:03:29.830: D/BluetoothMapService(11774): start()
08-19 23:03:29.832: D/BluetoothMapEmailSettingsLoader(11774): Found 0 applications
08-19 23:03:29.832: D/BluetoothMapEmailAppObserver(11774): createReceiver()
08-19 23:03:29.833: D/BluetoothMapEmailAppObserver(11774): initObservers()
08-19 23:03:29.833: D/BluetoothMapEmailAppObserver(11774): getEnabledAccountItems()
08-19 23:03:29.838: D/HeadsetPhoneState(11774): sendDeviceStateChanged. mService=1 mSignal=0 mRoam=0 mBatteryCharge=4
08-19 23:03:29.838: D/HeadsetStateMachine(11774): Disconnected process message: 11, size: 0
08-19 23:03:29.838: D/HeadsetPhoneState(11774): sendDeviceStateChanged. mService=1 mSignal=4 mRoam=0 mBatteryCharge=4
08-19 23:03:29.838: D/HeadsetStateMachine(11774): Disconnected process message: 11, size: 0
08-19 23:03:29.839: D/BluetoothAdapterState(11774): CURRENT_STATE=PENDING, MESSAGE = STARTED, isTurningOn=true, isTurningOff=false
08-19 23:03:29.839: I/bluedroid(11774): enable
08-19 23:03:29.839: I/GKI_LINUX(11774): gki_task_entry task_id=0 [BTU] starting
08-19 23:03:29.839: I/bt-btu(11774): btu_task pending for preload complete event
08-19 23:03:29.839: I/bt_hci_bdroid(11774): init
08-19 23:03:29.840: D/AuthorizationBluetoothService(7627): Received Bluetooth event: Intent { act=android.bluetooth.adapter.action.STATE_CHANGED flg=0x4000010 cmp=com.google.android.gms/.auth.be.proximity.authorization.bt.AuthorizationB luetoothService$BluetoothStateChangeReceiver (has extras) }.
08-19 23:03:29.842: I/bt_vendor(11774): init
08-19 23:03:29.842: I/bt_vnd_conf(11774): Attempt to load conf from /etc/bluetooth/bt_vendor.conf
08-19 23:03:29.842: I/bt_vnd_conf(11774): vnd_load_conf file >/etc/bluetooth/bt_vendor.conf< not found
08-19 23:03:29.842: D/bt_userial(11774): userial_init
08-19 23:03:29.879: I/bt_userial_vendor(11774): userial vendor open: opening /dev/ttyHS99
08-19 23:03:29.883: I/bt_userial_vendor(11774): device fd = 52 open
08-19 23:03:29.883: D/bt_userial(11774): Entering userial_read_thread()
08-19 23:03:29.937: D/HeadsetStateMachine(11774): Disconnected process message: 10, size: 0
08-19 23:03:30.168: D/HeadsetStateMachine(11774): Disconnected process message: 10, size: 0
08-19 23:03:32.801: D/audio_hw_primary(189): disable_audio_route: reset and update mixer path: low-latency-playback
08-19 23:03:32.801: D/audio_hw_primary(189): disable_snd_device: snd_device(2: speaker)
08-19 23:03:32.840: E/bt-btif(11774): ...preload_wait_timeout (retried:0/max-retry:0)...
08-19 23:03:32.842: D/bt_userial(11774): RX termination
08-19 23:03:32.842: W/bt_userial(11774): select_read return size <=0:-1, exiting userial_read_thread
08-19 23:03:32.842: D/bt_userial(11774): Leaving userial_read_thread()
08-19 23:03:32.842: I/bt_userial_vendor(11774): device fd = 52 close
08-19 23:03:32.947: W/bt-btu(11774): btu_task start abort!
08-19 23:03:32.948: I/GKI_LINUX(11774): gki_task task_id=0 [BTU] terminating
08-19 23:03:32.948: I/GKI_LINUX(11774): GKI_exit_task 0 done
08-19 23:03:32.948: I/GKI_LINUX(11774): GKI_shutdown(): task [BTU] terminated
08-19 23:03:32.948: I/GKI_LINUX(11774): GKI_exit_task 1 done
08-19 23:03:32.949: I/GKI_LINUX(11774): gki_task task_id=2 [A2DP-MEDIA] terminating
08-19 23:03:32.949: I/GKI_LINUX(11774): GKI_exit_task 2 done
08-19 23:03:32.954: D/BluetoothAdapterState(11774): CURRENT_STATE=PENDING, MESSAGE = DISABLED, isTurningOn=true, isTurningOff=false
08-19 23:03:32.954: E/BluetoothAdapterState(11774): Error enabling Bluetooth - hardware init failed
08-19 23:03:32.955: I/GKI_LINUX(11774): gki_task task_id=1 [BTIF] terminating
08-19 23:03:32.962: D/HeadsetService(11774): Received stop request...Stopping profile...
08-19 23:03:32.964: D/HeadsetStateMachine(11774): Exit Disconnected: -1
08-19 23:03:32.970: D/BluetoothAdapterProperties(11774): Setting state to 10
08-19 23:03:32.971: I/BluetoothAdapterState(11774): Bluetooth adapter state changed: 11-> 10
08-19 23:03:32.971: D/A2dpService(11774): Received stop request...Stopping profile...
08-19 23:03:32.971: D/BluetoothManagerService(782): Message: 60
08-19 23:03:32.971: D/BluetoothManagerService(782): MESSAGE_BLUETOOTH_STATE_CHANGE: prevState = 11, newState=10
08-19 23:03:32.971: D/BluetoothManagerService(782): Broadcasting onBluetoothStateChange(false) to 17 receivers.
08-19 23:03:32.972: D/A2dpStateMachine(11774): Exit Disconnected: -1
08-19 23:03:32.972: D/BluetoothA2dpSink(929): onBluetoothStateChange: up=false
08-19 23:03:32.973: E/BluetoothA2dpSink(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.bluetooth.BluetoothA2dpSink$1.onBluetoothS tateChange(BluetoothA2dpSink.java:139)
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.973: E/BluetoothA2dpSink(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.973: I/BluetoothAdapterState(11774): Entering OffState
08-19 23:03:32.974: D/BluetoothInputDevice(3298): onBluetoothStateChange: up=false
08-19 23:03:32.974: E/BluetoothInputDevice(3298): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.bluetooth.BluetoothInputDevice$1.onBluetoo thStateChange(BluetoothInputDevice.java:216)
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.974: E/BluetoothInputDevice(3298): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.975: D/HidService(11774): Received stop request...Stopping profile...
08-19 23:03:32.976: E/BluetoothPan(3298): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.976: E/BluetoothPan(3298): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.976: E/BluetoothPan(3298): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.976: E/BluetoothPan(3298): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.976: E/BluetoothPan(3298): at android.bluetooth.BluetoothPan$1.onBluetoothStateC hange(BluetoothPan.java:199)
08-19 23:03:32.976: E/BluetoothPan(3298): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.976: E/BluetoothPan(3298): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.976: D/BluetoothMap(3298): onBluetoothStateChange: up=false
08-19 23:03:32.977: E/BluetoothMap(3298): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.977: E/BluetoothMap(3298): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.977: E/BluetoothMap(3298): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.977: E/BluetoothMap(3298): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.977: E/BluetoothMap(3298): at android.bluetooth.BluetoothMap$1.onBluetoothStateC hange(BluetoothMap.java:64)
08-19 23:03:32.977: E/BluetoothMap(3298): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.977: E/BluetoothMap(3298): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.977: D/BluetoothA2dp(782): onBluetoothStateChange: up=false
08-19 23:03:32.977: D/HealthService(11774): Received stop request...Stopping profile...
08-19 23:03:32.978: E/BluetoothA2dp(782): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.bluetooth.BluetoothA2dp$1.onBluetoothState Change(BluetoothA2dp.java:126)
08-19 23:03:32.978: E/BluetoothA2dp(782): at com.android.server.BluetoothManagerService.sendBlu etoothStateCallback(BluetoothManagerService.java:7 10)
08-19 23:03:32.978: E/BluetoothA2dp(782): at com.android.server.BluetoothManagerService.bluetoo thStateChangeHandler(BluetoothManagerService.java: 1386)
08-19 23:03:32.978: E/BluetoothA2dp(782): at com.android.server.BluetoothManagerService.access$ 3300(BluetoothManagerService.java:61)
08-19 23:03:32.978: E/BluetoothA2dp(782): at com.android.server.BluetoothManagerService$Bluetoo thHandler.handleMessage(BluetoothManagerService.ja va:1113)
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.os.Handler.dispatchMessage(Handler.java:10 2)
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.os.Looper.loop(Looper.java:135)
08-19 23:03:32.978: E/BluetoothA2dp(782): at android.os.HandlerThread.run(HandlerThread.java:61 )
08-19 23:03:32.978: E/BluetoothA2dp(782): at com.android.server.ServiceThread.run(ServiceThread .java:46)
08-19 23:03:32.978: D/BluetoothHeadset(1198): onBluetoothStateChange: up=false
08-19 23:03:32.979: D/PanService(11774): Received stop request...Stopping profile...
08-19 23:03:32.979: D/BluetoothInputDevice(929): onBluetoothStateChange: up=false
08-19 23:03:32.979: E/BluetoothInputDevice(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.bluetooth.BluetoothInputDevice$1.onBluetoo thStateChange(BluetoothInputDevice.java:216)
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.979: E/BluetoothInputDevice(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.979: D/BluetoothHeadset(782): onBluetoothStateChange: up=false
08-19 23:03:32.980: D/BluetoothHeadset(929): onBluetoothStateChange: up=false
08-19 23:03:32.980: D/HeadsetStateMachine(11774): Unbinding service...
08-19 23:03:32.981: D/BluetoothHeadset(782): onBluetoothStateChange: up=false
08-19 23:03:32.982: E/BluetoothPan(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.982: E/BluetoothPan(929): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.982: E/BluetoothPan(929): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.982: E/BluetoothPan(929): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.982: E/BluetoothPan(929): at android.bluetooth.BluetoothPan$1.onBluetoothStateC hange(BluetoothPan.java:199)
08-19 23:03:32.982: E/BluetoothPan(929): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.982: E/BluetoothPan(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.985: D/BluetoothPbap(3298): onBluetoothStateChange: up=false
08-19 23:03:32.985: W/BluetoothHeadsetServiceJni(11774): Cleaning up Bluetooth Handsfree Interface...
08-19 23:03:32.985: W/BluetoothHeadsetServiceJni(11774): Cleaning up Bluetooth Handsfree callback object
08-19 23:03:32.985: D/BtGatt.DebugUtils(11774): handleDebugAction() action=null
08-19 23:03:32.985: D/BtGatt.GattService(11774): Received stop request...Stopping profile...
08-19 23:03:32.985: D/BtGatt.GattService(11774): stop()
08-19 23:03:32.985: D/BtGatt.AdvertiseManager(11774): advertise clients cleared
08-19 23:03:32.986: E/BluetoothPbap(3298): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.bluetooth.BluetoothPbap$1.onBluetoothState Change(BluetoothPbap.java:121)
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.986: E/BluetoothPbap(3298): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.986: D/BluetoothHeadsetClient(929): onBluetoothStateChange: up=false
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): java.lang.IllegalArgumentException: Service not registered: [email protected] a
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.bluetooth.BluetoothHeadsetClient$1.onBluet oothStateChange(BluetoothHeadsetClient.java:382)
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.987: E/BluetoothHeadsetClient(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.988: D/BluetoothMapService(11774): Received stop request...Stopping profile...
08-19 23:03:32.988: D/BluetoothMapService(11774): stop()
08-19 23:03:32.988: D/BluetoothAvrcpController(929): onBluetoothStateChange: up=false
08-19 23:03:32.988: D/BluetoothMapEmailAppObserver(11774): deinitObservers()
08-19 23:03:32.989: D/BluetoothMapEmailAppObserver(11774): removeReceiver()
08-19 23:03:32.989: E/BluetoothAvrcpController(929): java.lang.IllegalArgumentException: Service not registered: [email protected] 7b
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.bluetooth.BluetoothAvrcpController$1.onBlu etoothStateChange(BluetoothAvrcpController.java:80 )
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.989: E/BluetoothAvrcpController(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.989: D/BluetoothHeadset(782): onBluetoothStateChange: up=false
08-19 23:03:32.989: W/BluetoothHidServiceJni(11774): Cleaning up Bluetooth HID Interface...
08-19 23:03:32.989: W/bt-btif(11774): cleanup: HH disabling or disabled already, status = 0
08-19 23:03:32.989: W/BluetoothHidServiceJni(11774): Cleaning up Bluetooth GID callback object
08-19 23:03:32.990: W/BluetoothHealthServiceJni(11774): Cleaning up Bluetooth Health Interface...
08-19 23:03:32.990: W/BluetoothHealthServiceJni(11774): Cleaning up Bluetooth Health object
08-19 23:03:32.990: W/BluetoothPanServiceJni(11774): Cleaning up Bluetooth PAN Interface...
08-19 23:03:32.990: W/BluetoothPanServiceJni(11774): Cleaning up Bluetooth PAN callback object
08-19 23:03:32.991: D/BluetoothA2dp(929): onBluetoothStateChange: up=false
08-19 23:03:32.991: D/BtGatt.GattService(11774): cleanup()
08-19 23:03:32.991: W/bt-btif(11774): GATTC Module not enabled/already disabled
08-19 23:03:32.991: W/bt-btif(11774): GATTS Module not enabled/already disabled
08-19 23:03:32.991: D/BtGatt.AdvertiseManager(11774): advertise clients cleared
08-19 23:03:32.991: D/BluetoothMapService(11774): MAP Service closeService in
08-19 23:03:32.991: E/BluetoothA2dp(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.bluetooth.BluetoothA2dp$1.onBluetoothState Change(BluetoothA2dp.java:126)
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.991: E/BluetoothA2dp(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.991: D/BluetoothMapMasInstance(11774): MAP Service shutdown
08-19 23:03:32.991: D/BluetoothMapService(11774): cleanup()
08-19 23:03:32.991: D/BluetoothMapService(11774): MAP Service closeService in
08-19 23:03:32.992: D/BluetoothMap(929): onBluetoothStateChange: up=false
08-19 23:03:32.992: E/BluetoothMap(929): java.lang.IllegalArgumentException: Service not registered: [email protected]
08-19 23:03:32.992: E/BluetoothMap(929): at android.app.LoadedApk.forgetServiceDispatcher(Load edApk.java:1029)
08-19 23:03:32.992: E/BluetoothMap(929): at android.app.ContextImpl.unbindService(ContextImpl. java:1808)
08-19 23:03:32.992: E/BluetoothMap(929): at android.content.ContextWrapper.unbindService(Conte xtWrapper.java:551)
08-19 23:03:32.992: E/BluetoothMap(929): at android.bluetooth.BluetoothMap$1.onBluetoothStateC hange(BluetoothMap.java:64)
08-19 23:03:32.992: E/BluetoothMap(929): at android.bluetooth.IBluetoothStateChangeCallback$St ub.onTransact(IBluetoothStateChangeCallback.java:5 5)
08-19 23:03:32.992: E/BluetoothMap(929): at android.os.Binder.execTransact(Binder.java:446)
08-19 23:03:32.992: D/BluetoothManagerService(782): Bluetooth State Change Intent: 11 -> 10
08-19 23:03:32.997: E/BluetoothManagerService(782): recoverBluetoothServiceFromError
08-19 23:03:33.000: W/ContextImpl(3298): Calling a method in the system process without a qualified user: android.app.ContextImpl.startService:1692 android.content.ContextWrapper.startService:516 android.content.ContextWrapper.startService:516 com.android.settings.bluetooth.DockEventReceiver.b eginStartingService:134 com.android.settings.bluetooth.DockEventReceiver.o nReceive:115
08-19 23:03:33.020: D/DockEventReceiver(3298): finishStartingService: stopping service
08-19 23:03:33.037: D/AuthorizationBluetoothService(7627): Received Bluetooth event: Intent { act=android.bluetooth.adapter.action.STATE_CHANGED flg=0x4000010 cmp=com.google.android.gms/.auth.be.proximity.authorization.bt.AuthorizationB luetoothService$BluetoothStateChangeReceiver (has extras) }.
: E/(): Device disconnected

I've recently started having this same issue after updating to the latest 5.1.1. security release from a few weeks ago. I've tried factory resets, using both 5.1.1. factory images to reset, and looking at logcat to see if I could find any potential solutions. Nothing has seemed to help. I can occasionally get bluetooth to turn on, but it doesn't stay on for very long, and I can't consistently get it to turn on.
Have you had any luck getting an answer to what is causing the issue?

Is encryption possible at all with systemless root?

Just in case it could be useful to know, I'm running this, specifically the StockOnlyDebloated one (M4B30X as of writing), with SuperSU 2.78 systemless root.
The problem I'm having is that encryption refuses to ever start if I try to activate it. Everything shuts down, the green android thing is displayed, then the screen goes blank and it stays that way. My logcat in the moments preceding the supposed beginning of the encryption process reads as follows:
Code:
11-11 23:51:20.435 989 2940 D CryptdConnector: SND -> {7 cryptfs enablecrypto inplace password [scrubbed]}
11-11 23:51:20.442 149 159 D Cryptfs : Just asked init to shut down class main
11-11 23:51:20.442 989 1289 D VoldConnector: RCV <- {651 emulated 5}
11-11 23:51:20.445 289 289 I ServiceManager: service 'android.service.gatekeeper.IGateKeeperService' died
11-11 23:51:20.447 989 1288 D MountService: Volume emulated broadcasting ejecting to UserHandle{0}
11-11 23:51:20.448 1402 1402 D MtpService: onStorageStateChanged /storage/emulated/0 mounted -> ejecting
11-11 23:51:20.448 1402 1402 E MtpService: Missing MtpStorage for /storage/emulated/0
11-11 23:51:20.456 989 1301 E NetdConnector: got -1 reading with start = 0
11-11 23:51:20.456 989 1301 E NetdConnector: closing stream for netd
11-11 23:51:20.456 989 1301 E NetdConnector: Communications error: java.io.IOException: Connection refused
11-11 23:51:20.457 989 1301 E NetdConnector: Error in NativeDaemonConnector: java.io.IOException: Connection refused
11-11 23:51:20.457 989 1320 E mDnsConnector: got -1 reading with start = 0
11-11 23:51:20.457 989 1320 E mDnsConnector: closing stream for mdns
11-11 23:51:20.458 989 1320 E mDnsConnector: Communications error: java.io.IOException: Connection refused
11-11 23:51:20.458 989 1320 E mDnsConnector: Error in NativeDaemonConnector: java.io.IOException: Connection refused
11-11 23:51:20.556 159 159 W vold : type=1400 audit(0.0:794): avc: denied { read } for name="fd" dev="proc" ino=7289 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=dir permissive=0
11-11 23:51:20.556 159 159 W vold : type=1400 audit(0.0:795): avc: denied { getattr } for path="/proc/148/cwd" dev="proc" ino=59003 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:51:20.556 159 159 W vold : type=1400 audit(0.0:796): avc: denied { getattr } for path="/proc/148/root" dev="proc" ino=59004 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:51:20.566 159 159 W vold : type=1400 audit(0.0:797): avc: denied { getattr } for path="/proc/148/exe" dev="proc" ino=59005 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:51:20.460 989 1035 I ActivityManager: Start proc 7171:com.android.externalstorage/u0a8 for broadcast com.android.externalstorage/.MountReceiver
11-11 23:51:20.668 289 289 I ServiceManager: service 'user.xposed.app' died
11-11 23:51:20.668 289 289 I ServiceManager: service 'user.xposed.system' died
11-11 23:51:20.669 289 289 I ServiceManager: service 'android.security.keystore' died
11-11 23:51:20.711 289 289 I ServiceManager: service 'nfc' died
11-11 23:51:20.741 288 288 I lowmemorykiller: ActivityManager disconnected
11-11 23:51:20.741 288 288 I lowmemorykiller: Closing Activity Manager data connection
11-11 23:51:20.860 149 158 E FrameworkListener: read() failed (Connection reset by peer)
11-11 23:51:20.931 289 289 I ServiceManager: service 'telecom' died
11-11 23:51:20.931 289 289 I ServiceManager: service 'user' died
11-11 23:51:20.931 289 289 I ServiceManager: service 'procstats' died
11-11 23:51:20.931 289 289 I ServiceManager: service 'meminfo' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'gfxinfo' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'dbinfo' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'cpuinfo' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'permission' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'processinfo' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'sensorservice' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'battery' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'usagestats' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'webviewupdate' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'scheduling_policy' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'telephony.registry' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'media.camera.proxy' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'account' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'content' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'vibrator' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'consumer_ir' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'alarm' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'window' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'input' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'batterystats' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'appops' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'power' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'display' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'bluetooth_manager' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'input_method' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'accessibility' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'mount' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'uimode' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'lock_settings' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'deviceidle' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'device_policy' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'statusbar' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'clipboard' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'network_management' died
11-11 23:51:20.932 289 289 I ServiceManager: service 'textservices' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'network_score' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'netstats' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'netpolicy' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'package' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'activity' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'wifip2p' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'wifi' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'wifiscanner' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'rttmanager' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'ethernet' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'connectivity' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'servicediscovery' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'updatelock' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'notification' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'devicestoragemonitor' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'location' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'country_detector' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'search' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'dropbox' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'wallpaper' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'audio' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'DockObserver' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'midi' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'usb' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'serial' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'jobscheduler' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'backup' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'appwidget' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'voiceinteraction' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'diskstats' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'samplingprofiler' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'commontime_management' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'dreams' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'assetatlas' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'graphicsstats' died
11-11 23:51:20.933 289 289 I ServiceManager: service 'print' died
11-11 23:51:20.934 289 289 I ServiceManager: service 'restrictions' died
11-11 23:51:20.934 289 289 I ServiceManager: service 'media_session' died
11-11 23:51:20.934 289 289 I ServiceManager: service 'media_router' died
11-11 23:51:20.934 289 289 I ServiceManager: service 'fingerprint' died
11-11 23:51:20.934 289 289 I ServiceManager: service 'trust' died
11-11 23:51:20.934 289 289 I ServiceManager: service 'launcherapps' died
11-11 23:51:20.934 289 289 I ServiceManager: service 'media_projection' died
11-11 23:51:20.934 289 289 I ServiceManager: service 'imms' died
11-11 23:51:20.935 290 290 D SurfaceFlinger: Set power mode=2, type=0 flinger=0xb6b24000
11-11 23:51:20.935 290 290 D SurfaceFlinger: Screen type=0 is already mode=2
11-11 23:51:20.941 289 289 I ServiceManager: service 'simphonebook' died
11-11 23:51:20.941 289 289 I ServiceManager: service 'iphonesubinfo' died
11-11 23:51:20.941 289 289 I ServiceManager: service 'isms' died
11-11 23:51:20.941 289 289 I ServiceManager: service 'phone' died
11-11 23:51:20.942 289 289 I ServiceManager: service 'carrier_config' died
11-11 23:51:20.942 289 289 I ServiceManager: service 'sip' died
11-11 23:51:20.942 289 289 I ServiceManager: service 'isub' died
11-11 23:51:21.004 289 289 I ServiceManager: service 'media.sound_trigger_hw' died
11-11 23:51:21.004 289 289 I ServiceManager: service 'media.radio' died
11-11 23:51:21.004 289 289 I ServiceManager: service 'media.audio_flinger' died
11-11 23:51:21.004 289 289 I ServiceManager: service 'media.player' died
11-11 23:51:21.004 289 289 I ServiceManager: service 'media.resource_manager' died
11-11 23:51:21.004 289 289 I ServiceManager: service 'media.camera' died
11-11 23:51:21.004 289 289 I ServiceManager: service 'media.audio_policy' died
11-11 23:51:21.005 289 289 I ServiceManager: service 'drm.drmManager' died
11-11 23:51:21.051 7186 7186 I BootAnimation: boot animation disabled
11-11 23:51:40.717 159 159 W vold : type=1400 audit(0.0:798): avc: denied { read } for name="fd" dev="proc" ino=7289 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=dir permissive=0
11-11 23:51:40.717 159 159 W vold : type=1400 audit(0.0:799): avc: denied { getattr } for path="/proc/148/cwd" dev="proc" ino=59003 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:51:40.717 159 159 W vold : type=1400 audit(0.0:800): avc: denied { getattr } for path="/proc/148/root" dev="proc" ino=59004 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:51:40.717 159 159 W vold : type=1400 audit(0.0:801): avc: denied { getattr } for path="/proc/148/exe" dev="proc" ino=59005 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:51:40.747 149 159 E ProcessKiller: Process daemonsu:0:650 (3619) has open file /data/data/eu.chainfire.supersu/logs/L14789021760000.GRANTED.ADB shell
11-11 23:51:40.750 149 159 E Cryptfs : unmounting /data failed: Device or resource busy
11-11 23:51:40.757 159 159 W vold : type=1400 audit(0.0:802): avc: denied { read } for name="fd" dev="proc" ino=7289 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=dir permissive=0
11-11 23:51:40.757 159 159 W vold : type=1400 audit(0.0:803): avc: denied { getattr } for path="/proc/148/cwd" dev="proc" ino=59003 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:51:40.757 159 159 W vold : type=1400 audit(0.0:804): avc: denied { getattr } for path="/proc/148/root" dev="proc" ino=59004 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:51:40.757 159 159 W vold : type=1400 audit(0.0:805): avc: denied { getattr } for path="/proc/148/exe" dev="proc" ino=59005 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:51:40.783 149 159 E ProcessKiller: Process daemonsu:0:650 (3619) has open file /data/data/eu.chainfire.supersu/logs/L14789021760000.GRANTED.ADB shell
11-11 23:51:40.783 149 159 W ProcessKiller: Sending Killed to process 3619
11-11 23:51:40.864 149 159 D Cryptfs : Just asked init to shut down class main
11-11 23:51:40.864 149 159 W vold : emulated unmount requires state mounted
11-11 23:52:00.876 159 159 W vold : type=1400 audit(0.0:806): avc: denied { read } for name="fd" dev="proc" ino=7289 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=dir permissive=0
11-11 23:52:00.876 159 159 W vold : type=1400 audit(0.0:807): avc: denied { getattr } for path="/proc/148/cwd" dev="proc" ino=59003 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:52:00.876 159 159 W vold : type=1400 audit(0.0:808): avc: denied { getattr } for path="/proc/148/root" dev="proc" ino=59004 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:52:00.876 159 159 W vold : type=1400 audit(0.0:809): avc: denied { getattr } for path="/proc/148/exe" dev="proc" ino=59005 scontext=u:r:vold:s0 tcontext=u:r:logd:s0 tclass=lnk_file permissive=0
11-11 23:52:00.906 149 159 E Cryptfs : unmounting /data failed: Device or resource busy
11-11 23:52:00.906 149 159 W SocketClient: write error (Broken pipe)
11-11 23:52:00.906 149 159 W SocketClient: Unable to send msg '200 7 -1'
As you can see, the encryption begins, everything shuts down nicely, then /data fails to dismount because something in it is still in use and everything goes dead silent (it's been more than half an hour and logcat still gives no signs of activity). If I force a reboot, everything works fine and dandy as if the encryption never occured, because it didn't.
I've done the searching, and the closest thing to this issue I've found is this thread, but there's no solution there, only the exact same problem.
I strongly suspect that systemless root is the cause of this, because after the failed /data dismount attempt, /su shows up as still mounted, /su is the mount point of /data/su.img, and also gives a "device busy" error if I try to dismount it manually, probably because of the running sukernel and daemonsu processes. I could kill those, but something about them (like the fact that there's one named daemonsu:mount:master) makes me feel like that's not a good idea at all, so I haven't tried yet. Disabling root in SuperSU changes nothing.
Any clues on how to fix or at least work around this?

SuicideFlasher said:
Just in case it could be useful to know, I'm running this, specifically the StockOnlyDebloated one (M4B30X as of writing), with SuperSU 2.78 systemless root.
The problem I'm having is that encryption refuses to ever start if I try to activate it. Everything shuts down, the green android thing is displayed, then the screen goes blank and it stays that way. My logcat in the moments preceding the supposed beginning of the encryption process reads as follows:
Code:
8< snip >8
As you can see, the encryption begins, everything shuts down nicely, then /data fails to dismount because something in it is still in use and everything goes dead silent (it's been more than half an hour and logcat still gives no signs of activity). If I force a reboot, everything works fine and dandy as if the encryption never occured, because it didn't.
I've done the searching, and the closest thing to this issue I've found is this thread, but there's no solution there, only the exact same problem.
I strongly suspect that systemless root is the cause of this, because after the failed /data dismount attempt, /su shows up as still mounted, /su is the mount point of /data/su.img, and also gives a "device busy" error if I try to dismount it manually, probably because of the running sukernel and daemonsu processes. I could kill those, but something about them (like the fact that there's one named daemonsu:mount:master) makes me feel like that's not a good idea at all, so I haven't tried yet. Disabling root in SuperSU changes nothing.
Any clues on how to fix or at least work around this?
Click to expand...
Click to collapse
For as long as I remember, I never was able to do the initial encryption on Marshmallow with systemless SuperSU installed.
As you found out, /data cannot be unmounted because /data/su.img is still mounted on /su.
I was always able to workaround that problem by:
booting into twrp
making a full backup (with system, boot and data)
reinstalling my rom (stock or custom), wiping data but not sdcard
booting into the rom, encrypt
boot back into twrp and restore
Alternately, you can try uninstalling SuperSU, encrypt and reinstall SuperSU.

That would work, but I found my own workaround in the meantime.
Reboot into recovery
Rename /data/su.img to anything else
Reboot into system
Rename it back to su.img (this can be done at any point from now on)
Encrypt
???
Profit!
One boot with su.img unavailable didn't break anything, to my knowledge.
UPDATE: If you're using the original kernel that came with your rom, you can just fastboot boot the original boot.img from the package. This will give you a one-time unrooted boot with a single command.

Hi, thanks for sharinv this hints!
I'm about to try this solution with Samsung S7... I'm wondering about step 4. I can't find this file after reboot into system. I guess, it's because /data is not mounted. I also tried to mount it manually (it should be /dev/sda15 according to what I saw in TWRP). I guess, I won't be even able to mount, since root is deactivated, right?
Any ideas, how I can access this file in order to rename?
As far as I understand, I won't be able to access this file from TWRP after encryption (since TWRP doesn't support Samsung encryption).

bruzzy said:
Hi, thanks for sharinv this hints!
I'm about to try this solution with Samsung S7... I'm wondering about step 4. I can't find this file after reboot into system. I guess, it's because /data is not mounted. I also tried to mount it manually (it should be /dev/sda15 according to what I saw in TWRP). I guess, I won't be even able to mount, since root is deactivated, right?
Any ideas, how I can access this file in order to rename?
As far as I understand, I won't be able to access this file from TWRP after encryption (since TWRP doesn't support Samsung encryption).
Click to expand...
Click to collapse
Did you succeeded? Did you encrypt the data partition and survive?

Android 12 cellbroadcastreceiver permission denial causing high CPU usage in com.android.phone process?

(New forum member here, hope I chose the right topic. Cross-post from https://android.stackexchange.com/q/249383/37933, hope that's OK. Thanks!)
Every since my Nokia 5.3 was upgraded to Andoid 12 recently, the battery suffers from processes com.android.phone and qcrild using the CPU a fair amount. It is not completely reproducible, but it happens especially when the the phone is locked, or at least the screen is off.
I observe that increased CPU usage via adb shell top -u radio.
At that same time adb shell dumpsys phone shows a lot of the following:
Code:
❯ adb shell dumpsys phone
------- PhoneGlobals -------
mPrevRoamingNotification=0
mDefaultDataSubId=1
mDataRoamingNotifLog:
isSmsCapable=true
2022-11-23T13:56:23.510818 - dataAllowed=true, reasons=Data allowed reason: NORMAL
2022-11-23T13:56:23.512116 - dataAllowed=true, reasons=Data allowed reason: NORMAL
[snip rest of the output]
And that seems to always immediately follow these adb shell logcat -d phone warnings about ...without a qualified user and Permission Denial to read the phone state:
Code:
11-23 13:56:23.460 1681 3481 W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcastMultiplePermissions:1206 com.android.server.TelephonyRegistry.broadcastServiceStateChanged:2880 com.android.server.TelephonyRegistry.notifyServiceStateForPhoneId:1608 com.android.internal.telephony.ITelephonyRegistry$Stub.onTransact:455 android.os.Binder.execTransactInternal:1184
11-23 13:56:23.461 1681 3481 W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcastMultiplePermissions:1256 android.content.Context.sendBroadcastMultiplePermissions:2215 com.android.server.TelephonyRegistry.broadcastServiceStateChanged:2884 com.android.server.TelephonyRegistry.notifyServiceStateForPhoneId:1608 com.android.internal.telephony.ITelephonyRegistry$Stub.onTransact:455
11-23 13:56:23.462 1681 3481 W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcastMultiplePermissions:1256 android.content.Context.sendBroadcastMultiplePermissions:2215 com.android.server.TelephonyRegistry.broadcastServiceStateChanged:2891 com.android.server.TelephonyRegistry.notifyServiceStateForPhoneId:1608 com.android.internal.telephony.ITelephonyRegistry$Stub.onTransact:455
11-23 13:56:23.463 1681 2031 W BroadcastQueue: Permission Denial: receiving Intent { act=android.intent.action.SERVICE_STATE flg=0x1000010 (has extras) } to com.google.android.cellbroadcastreceiver/com.android.cellbroadcastreceiver.CellBroadcastReceiver requires android.permission.READ_PHONE_STATE due to sender android (uid 1001)
11-23 13:56:23.463 1681 2031 W BroadcastQueue: Permission Denial: receiving Intent { act=android.intent.action.SERVICE_STATE flg=0x1000010 (has extras) } to com.google.android.cellbroadcastreceiver/com.android.cellbroadcastreceiver.CellBroadcastReceiver requires android.permission.ACCESS_FINE_LOCATION due to sender android (uid 1001)
11-23 13:56:23.464 1681 3481 W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcastMultiplePermissions:1256 android.content.Context.sendBroadcastMultiplePermissions:2215 com.android.server.TelephonyRegistry.broadcastServiceStateChanged:2895 com.android.server.TelephonyRegistry.notifyServiceStateForPhoneId:1608 com.android.internal.telephony.ITelephonyRegistry$Stub.onTransact:455
11-23 13:56:23.465 1681 2031 W BroadcastQueue: Permission Denial: receiving Intent { act=android.intent.action.SERVICE_STATE flg=0x1000010 (has extras) } to com.google.android.cellbroadcastreceiver/com.android.cellbroadcastreceiver.CellBroadcastReceiver requires android.permission.READ_PHONE_STATE due to sender android (uid 1001)
And that results in a lot of logging that seems to be about refreshing the data about my mobile carrier (onRefreshCarrierInfo()), which may be where the CPU time is actually spent (following log capture is from a later occurrence):
Code:
11-23 14:18:08.592 2936 2936 D ServiceStateProvider: subId=1
11-23 14:18:08.592 2462 2640 I CustomizedNetworkIcon: getConfigValueByKey: oem_key_rat_name_of_4g_int=0
11-23 14:18:08.595 2462 2640 I CustomizedNetworkIcon: getConfigValueByKey: oem_display_lte_icon_int=1
11-23 14:18:08.597 2936 2936 I Telephony: TelecomAccountRegistry: onSubscriptionsChanged - reregister accounts
11-23 14:18:08.598 1676 2040 D CompatibilityInfo: mCompatibilityFlags - 0
11-23 14:18:08.598 2936 2936 D Telephony: isEmergencyPreferredAccount: subId=1, activeData=1
11-23 14:18:08.598 1676 2040 D CompatibilityInfo: applicationDensity - 280
11-23 14:18:08.598 2936 2936 D Telephony: isEmergencyPreferredAccount: Device does not require preference.
11-23 14:18:08.598 1676 2040 D CompatibilityInfo: applicationScale - 1.0
11-23 14:18:08.600 31066 31066 D CellBroadcastReceiver: onReceive Intent { act=android.intent.action.SERVICE_STATE flg=0x1000010 cmp=com.google.android.cellbroadcastreceiver/com.android.cellbroadcastreceiver.CellBroadcastReceiver (has extras) }
11-23 14:18:08.600 31066 31066 D CellBroadcastReceiver: onServiceStateChanged, ss: 0
11-23 14:18:08.600 31066 31066 D CellBroadcastReceiver: networkOperator: 20416
11-23 14:18:08.600 31066 31066 D CellBroadcastReceiver: update supported roaming operator as
11-23 14:18:08.601 2936 2936 I Telephony: isRttCurrentlySupported -- regular acct, hasVoiceAvailability: false
11-23 14:18:08.601 2936 2936 I Telephony: isRttSupported: false
11-23 14:18:08.601 2936 2936 I Telephony: isUserRttSettingOn: false
11-23 14:18:08.601 2936 2936 I Telephony: alwaysAllowWhileRoaming: false
11-23 14:18:08.601 2936 2936 I Telephony: isRoaming: false
11-23 14:18:08.601 2936 2936 I Telephony: isOnWfc: false
11-23 14:18:08.607 2462 2640 E CustomizedImsIcon: customizedVoLTEandVoWiFiIcon:
11-23 14:18:08.608 2462 4440 I CustomizedImsIcon: [0] customizedImsIcon:++
11-23 14:18:08.608 2462 4440 D CustomizedImsIcon: isAirplaneModeOn: false
11-23 14:18:08.608 2462 4440 D CustomizedImsIcon: customizedImsIcon:clear all vowifi icon which is added with TAG when airplane is off
11-23 14:18:08.608 2462 4440 I CustomizedImsIcon: [0] isOperaterCustomized:mccmnc=20416
11-23 14:18:08.609 2462 4440 I CustomizedImsIcon: [0] isOperaterClaroCustomized:mccmnc=20416
11-23 14:18:08.609 2462 4440 I CustomizedImsIcon: [0] isCustomizedBySkuId:isCustomized=false
11-23 14:18:08.609 2462 4440 D CustomizedImsIcon: getSimCount: simCount=1
11-23 14:18:08.610 2462 4440 I CustomizedImsIcon: [0] isWifiCallingAvailable: isImsRegistered is false
11-23 14:18:08.610 2462 4440 I CustomizedImsIcon: [0] isShowVoWiFiIcon: isAllowed=false
11-23 14:18:08.612 2462 4440 I CustomizedImsIcon: [0] isAllowShowVoLteIcon:OEM_ALLOW_SHOW_VOLTE_ICON_BOOL=false phoneId=0
11-23 14:18:08.612 2462 4440 I CustomizedImsIcon: [0] isShowVolteIcon: isAllowed=false
11-23 14:18:08.613 2462 4440 I CustomizedImsIcon: [0] customizedImsIcon:--
11-23 14:18:08.615 2936 2936 I Telephony: reRegisterPstnPhoneAccount: subId: 1 - no change
11-23 14:18:08.615 2936 2936 D CallNotifier: updatePhoneStateListeners: update CF notifications.
11-23 14:18:08.616 2936 2936 I NotificationMgr: updateCfi: subId= 1, visible=N
11-23 14:18:08.616 2936 2936 I NotificationMgr: updateMwi(): subId 1 update to false
11-23 14:18:08.618 2462 2462 D KeyguardUpdateMonitor: received broadcast android.intent.action.SERVICE_STATE
11-23 14:18:08.618 2462 2462 V KeyguardUpdateMonitor: action android.intent.action.SERVICE_STATE serviceState={mVoiceRegState=0(IN_SERVICE), mDataRegState=0(IN_SERVICE), mChannelNumber=1656, duplexMode()=1, mCellBandwidths=[], mOperatorAlphaLong=Simpel, mOperatorAlphaShort=Simpel, isManualNetworkSelection=false(automatic), getRilVoiceRadioTechnology=14(LTE), getRilDataRadioTechnology=14(LTE), mCssIndicator=unsupported, mNetworkId=-1, mSystemId=-1, mCdmaRoamingIndicator=-1, mCdmaDefaultRoamingIndicator=-1, mIsEmergencyOnly=false, isUsingCarrierAggregation=false, mArfcnRsrpBoost=0, mNetworkRegistrationInfos=[NetworkRegistrationInfo{ domain=CS transportType=WWAN registrationState=HOME roamingType=NOT_ROAMING accessNetworkTechnology=LTE rejectCause=0 emergencyEnabled=false availableServices=[VOICE,SMS,VIDEO] cellIdentity=CellIdentityLte:{ mCi=70817281 mPci=66 mTac=527 mEarfcn=1656 mBands=[] mBandwidth=2147483647 mMcc=204 mMnc=16 mAlphaLong=Simpel mAlphaShort=Simpel mAdditionalPlmns={} mCsgInfo=null} voiceSpecificInfo=VoiceSpecificRegistrationInfo { mCssSupported=false mRoamingIndicator=1 mSystemIsInPrl=-1 mDefaultRoamingIndicator=-1} dataSpecificInfo=null nrState=**** rRplmn=20416 isUsingCarrierAggregation=false}, NetworkRegistrationInfo{ domain=PS transportType=WWAN registrationState=HOME roamingType=NOT_ROAMING accessNetworkTechnology=LTE rejectCause=0 emergencyEnabled=false availableServices=[DATA] cellIdentity=CellIdentityLte:{ mCi=70817281 mPci=66 mTac=527 mEarfcn=1656 mBands=[] mBandwidth=2147483647 mMcc=204 mMnc=16 mAlphaLong=Simpel mAlphaShort=Simpel mAdditionalPlmns={} mCsgInfo=null} voiceSpecificInfo=null dataSpecificInfo=android.telephony.DataSpecificRegistrationInfo :{ maxDataCalls = 20 isDcNrRestricted = false isNrAvailable = false isEnDcAvailable = false LteVopsSupportInfo : mVopsSupport = 3 mEmcBearerSupport = 2 } nrState=**** rRplmn=20416 isUsingCarrierAggregation=false}], mNrFrequencyRange=0, mOperatorAlphaLongRaw=Simpel, mOperatorAlphaShortRaw=Simpel, mIsDataRoamingFromRegistration=false, mIsIwlanPreferred=false} subId=1
[...snipping a lot more similar logging...]
What could be wrong? Or at least, how can I further debug this?

After more research, my current best quess is that the Qualcomm RIL driver (presumably via qcrild) very often sends a '1002 unsolicited response (voice) network state changed' event: com.android.phone logs
Code:
D RILJ : Unsol response received; Sending ack to ril.cpp [PHONE0]
D RILJ : [UNSL]< UNSOL_RESPONSE_NETWORK_STATE_CHANGED [PHONE0]
often 10+ times per second.