Gmscore: UnifiedNlp is not registered at boot

[Iey4iej3]

I refer to this post for a background, especially a filtered logcat.

microG version: 0.2.8.17785-4 (06c8b76), installed as a system app in LOS microG for polaris, build 20191005

As displayed in the self-check of microG, UnifiedNlp is not registered in the system, which is also reflected by the following log messages:

10-21 10:19:52.172 1258 1324 W LocationProviderProxy-network: Odd, no component found for service com.android.location.service.v3.NetworkLocationProvider
10-21 10:19:52.174 1258 1324 W LocationProviderProxy-fused: Odd, no component found for service com.android.location.service.FusedLocationProvider

[rKsanu2MMYvypWePtQWM]

0.2.9 introduced this issue for me as well.

[ProjectMoon]

Are there Angy workarounds for this until it's fixed? Cannot downgrade since I'm using LineageOS for MicroG

[FishCoding]

@ProjectMoon Even running this version with MicroG you can downgrade the MicroG core removing it from /system/priv-app and copying another time the wished version.

[Iey4iej3]

Does that have any real consequences (namely, apps fail to obtain the location or the requests are significantly delayed, I mean, noticeable affects in other apps) in your cases? I also posted https://github.com/microg/android_packages_apps_GmsCore/issues/960#issue-510504336 and I don't know whether you suffer from the same thing (I don't know whether the two are essentially correlated). (And if so, whether these are also only introduced in the latest release?)

[ProjectMoon]

This issue only started happening to me with this release

[Iey4iej3]

I also meant to ask real consequences of this. I posted this for UnifiedNlp not being recognized by the system, and https://github.com/microg/android_packages_apps_GmsCore/issues/960#issue-510504336 for the real issues that I encountered.

[ProjectMoon]

I was able to downgrade using Aurora Droid. It uninstalled the update. Now I'm back on the system version.

[Iey4iej3]

I forget to say that I am on the system version.

[Vavun]

microG crashes if navigation is enabled. So it is the reason of unchecked UnifiedNlp
Just try to disable navigation and reboot again.

Have same issue on LineageOS for microG 16.0

10-22 19:49:14.484 V/SettingsProvider( 1257): Notifying for 0: content://settings/secure/location_providers_allowed
10-22 19:49:14.488 E/JavaBinder( 2412): *** Uncaught remote exception!  (Exceptions are not yet supported across processes.)
10-22 19:49:14.488 E/JavaBinder( 2412): java.lang.AbstractMethodError: abstract method "void com.android.location.provider.LocationProviderBase.onEnable()"
10-22 19:49:14.488 E/JavaBinder( 2412):     at com.android.location.provider.LocationProviderBase$Service.enable(LocationProviderBase.java:82)
10-22 19:49:14.488 E/JavaBinder( 2412):     at com.android.internal.location.ILocationProvider$Stub.onTransact(ILocationProvider.java:55)
10-22 19:49:14.488 E/JavaBinder( 2412):     at android.os.Binder.execTransact(Binder.java:731)
--------- beginning of crash
10-22 19:49:14.489 E/AndroidRuntime( 2412): FATAL EXCEPTION: Binder:2412_3
10-22 19:49:14.489 E/AndroidRuntime( 2412): Process: com.google.android.gms, PID: 2412
10-22 19:49:14.489 E/AndroidRuntime( 2412): java.lang.AbstractMethodError: abstract method "void com.android.location.provider.LocationProviderBase.onEnable()"
10-22 19:49:14.489 E/AndroidRuntime( 2412):     at com.android.location.provider.LocationProviderBase$Service.enable(LocationProviderBase.java:82)
10-22 19:49:14.489 E/AndroidRuntime( 2412):     at com.android.internal.location.ILocationProvider$Stub.onTransact(ILocationProvider.java:55)
10-22 19:49:14.489 E/AndroidRuntime( 2412):     at android.os.Binder.execTransact(Binder.java:731)
10-22 19:49:14.490 V/SettingsProvider( 1257): Notifying for 0: content://settings/secure/location_providers_allowed
10-22 19:49:14.517 I/ActivityManager( 1257): Showing crash dialog for package com.google.android.gms u0
10-22 19:49:14.587 W/StaticLayout( 1257): maxLineHeight should not be -1.  maxLines:2 lineCount:2
10-22 19:49:14.589 I/chatty  ( 1257): uid=1000(system) android.ui identical 3 lines
10-22 19:49:14.589 W/StaticLayout( 1257): maxLineHeight should not be -1.  maxLines:2 lineCount:2
10-22 19:49:14.735 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:14.803 W/Looper  ( 1257): Slow dispatch took 206ms android.ui h=android.view.Choreographer$FrameHandler c=android.view.Choreographer$FrameDisplayEventReceiver@4386d54 m=0
10-22 19:49:14.804 W/Looper  ( 1257): Slow delivery took 212ms android.ui h=com.android.server.policy.PhoneWindowManager$PolicyHandler c=com.android.server.policy.PhoneWindowManager$21@4e54fd m=0
10-22 19:49:14.806 W/Looper  ( 1257): Drained
10-22 19:49:14.736 I/chatty  (  483): uid=1000(system) /system/bin/surfaceflinger identical 1 line
10-22 19:49:14.738 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:14.819 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 1
10-22 19:49:14.819 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:14.819 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:14.820 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 1
10-22 19:49:14.820 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:14.820 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:14.820 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:14.820 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:14.820 E/ANDR-PERF-TARGET(  474): Eror: Target not initialized
10-22 19:49:14.820 E/ANDR-PERF-OPTSHANDLER(  474): Invalid core no. 0
10-22 19:49:14.820 E/ANDR-PERF-OPTSHANDLER(  474): Request on invalid core or cluster
10-22 19:49:14.820 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [3, 0]
10-22 19:49:14.820 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:14.820 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:14.820 E/ANDR-PERF-TARGET(  474): Eror: Target not initialized
10-22 19:49:14.820 E/ANDR-PERF-OPTSHANDLER(  474): Invalid core no. 0
10-22 19:49:14.820 E/ANDR-PERF-OPTSHANDLER(  474): Request on invalid core or cluster
10-22 19:49:14.820 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [3, 0]
10-22 19:49:14.835 I/bt_a2dp_hw(  481): suspend_audio_datapath: state 1
10-22 19:49:14.835 W/bt_btif ( 1490): btif_a2dp_recv_ctrl_data: a2dp-ctrl-cmd : A2DP_CTRL_CMD_SUSPEND
10-22 19:49:14.835 I/btif_av ( 1490): btif_av_stream_started_ready: Peer 2c:ba:ba:67:c8:e1 : state=3 flags=0x0(None) ready=1
10-22 19:49:14.835 I/btif_av ( 1490): btif_av_stream_suspend
10-22 19:49:14.835 W/bt_btif ( 1490): btif_a2dp_recv_ctrl_data: a2dp-ctrl-cmd : A2DP_CTRL_CMD_SUSPEND DONE
10-22 19:49:14.835 I/btif_av ( 1490): virtual bool BtifAvStateMachine::StateStarted::ProcessEvent(uint32_t, void *): Peer 2c:ba:ba:67:c8:e1 : event=BTIF_AV_SUSPEND_STREAM_REQ_EVT(0x1d) flags=0x0(None)
10-22 19:49:14.835 I/bt_btif_a2dp_source( 1490): btif_a2dp_source_set_tx_flush: enable=true state=STATE_RUNNING
10-22 19:49:14.835 I/bt_bta_av( 1490): BTA_AvStop: handle=65 suspend=true
10-22 19:49:14.836 E/bt_btif ( 1490): bta_av_str_stopped: peer 2c:ba:ba:67:c8:e1 handle:65 audio_open_cnt:1, p_data 0x784b551348 start:1
10-22 19:49:14.836 W/bt_btif ( 1490): bta_dm_rm_cback:2, status:6
10-22 19:49:14.855 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:14.888 I/chatty  (  483): uid=1000(system) /system/bin/surfaceflinger identical 5 lines
10-22 19:49:14.904 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:14.904 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:14.904 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:14.904 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 1, 0
10-22 19:49:14.905 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:14.915 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:14.915 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:14.915 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:14.921 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:14.921 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:14.925 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:14.925 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:14.925 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:14.936 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:14.936 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:14.936 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:14.946 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:14.946 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:14.946 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:14.946 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 25 to 14
10-22 19:49:14.957 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:14.957 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:14.957 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:14.967 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:14.967 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:14.967 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:14.977 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:14.978 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:14.978 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:14.978 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 36 to 14
10-22 19:49:14.988 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:14.988 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:14.988 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:14.999 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:14.999 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:14.999 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.004 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:15.006 I/chatty  (  483): uid=1000(system) /system/bin/surfaceflinger identical 2 lines
10-22 19:49:15.006 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:15.009 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.009 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.009 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.009 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 47 to 14
10-22 19:49:15.020 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.020 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.020 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.030 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.030 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.030 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.041 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.041 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.041 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.041 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 57 to 14
10-22 19:49:15.051 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.051 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.051 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.062 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.062 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.062 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.072 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.072 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.072 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.072 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 68 to 14
10-22 19:49:15.083 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.083 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.083 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.093 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.093 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.093 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.103 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.104 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.104 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.104 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 79 to 14
10-22 19:49:15.114 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.114 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.114 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.124 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.125 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.125 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.135 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.135 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.135 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.135 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 90 to 14
10-22 19:49:15.145 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.145 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.145 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.156 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.156 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.156 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.166 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.166 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.166 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.167 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 101 to 14
10-22 19:49:15.177 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.177 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.177 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.187 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.187 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.187 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.198 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.198 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.198 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.198 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 112 to 14
10-22 19:49:15.209 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.209 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.209 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.219 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.219 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.219 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.229 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.230 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.230 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.230 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 123 to 14
10-22 19:49:15.240 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.240 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.240 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.251 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.251 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.251 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.261 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.261 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.261 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.261 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 133 to 14
10-22 19:49:15.272 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.272 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.272 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.282 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.282 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.282 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.293 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.293 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.293 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.293 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 144 to 14
10-22 19:49:15.303 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.303 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.303 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.314 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.314 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.314 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.324 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.324 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.325 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.325 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 155 to 14
10-22 19:49:15.335 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.335 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.335 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.345 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.345 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.345 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.356 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.356 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.356 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.356 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 166 to 14
10-22 19:49:15.366 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.366 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.366 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.367 W/bt_btif ( 1490): bta_dm_rm_cback:2, status:6
10-22 19:49:15.367 I/btif_av ( 1490): virtual bool BtifAvStateMachine::StateStarted::ProcessEvent(uint32_t, void *): Peer 2c:ba:ba:67:c8:e1 : event=BTA_AV_SUSPEND_EVT(0xf) status=0 initiator=1 flags=0x1(LOCAL_SUSPEND_PENDING)
10-22 19:49:15.367 I/bt_btif_a2dp( 1490): btif_a2dp_on_suspended: ## ON A2DP SUSPENDED ## p_av_suspend=0x78567eda58
10-22 19:49:15.367 I/bt_btif_a2dp_source( 1490): btif_a2dp_source_on_suspended: state=STATE_RUNNING
10-22 19:49:15.367 I/bt_btif_a2dp_source( 1490): btif_a2dp_source_stop_audio_req: state=STATE_RUNNING
10-22 19:49:15.367 I/btif_av ( 1490): btif_report_audio_state: peer_address=2c:ba:ba:67:c8:e1 state=1
10-22 19:49:15.368 I/BluetoothA2dpServiceJni( 1490): bta2dp_audio_state_callback
10-22 19:49:15.368 D/A2dpNativeInterface( 1490): onAudioStateChanged: A2dpStackEvent {type:EVENT_TYPE_AUDIO_STATE_CHANGED, device:2C:BA:BA:67:C8:E1, value1:STOPPED}
10-22 19:49:15.368 D/A2dpStateMachine( 1490): handleMessage: E msg.what=101
10-22 19:49:15.368 D/A2dpStateMachine( 1490): processMsg: Connected
10-22 19:49:15.368 D/A2dpStateMachine( 1490): Connected process message(2C:BA:BA:67:C8:E1): STACK_EVENT
10-22 19:49:15.369 D/A2dpStateMachine( 1490): Connected: stack event: A2dpStackEvent {type:EVENT_TYPE_AUDIO_STATE_CHANGED, device:2C:BA:BA:67:C8:E1, value1:STOPPED}
10-22 19:49:15.369 I/A2dpStateMachine( 1490): Connected: stopped playing: 2C:BA:BA:67:C8:E1
10-22 19:49:15.369 D/A2dpStateMachine( 1490): A2DP Playing state : device: 2C:BA:BA:67:C8:E1 State:PLAYING->NOT_PLAYING
10-22 19:49:15.370 I/Avrcp   ( 1490): Skipping update due to invalid playback state
10-22 19:49:15.371 D/A2dpStateMachine( 1490): handleMessage: X
10-22 19:49:15.377 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.377 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.377 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.387 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.387 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.387 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.387 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 177 to 14
10-22 19:49:15.398 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.398 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.398 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.408 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.408 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.408 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.418 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.419 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.419 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.419 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 188 to 14
10-22 19:49:15.429 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.429 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.429 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.439 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.440 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.440 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.450 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.450 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.450 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.450 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 198 to 14
10-22 19:49:15.461 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.461 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.461 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.471 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.471 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.471 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.482 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.482 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.482 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.482 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 209 to 14
10-22 19:49:15.492 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.492 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.492 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.503 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.503 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.503 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.513 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.513 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.513 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.513 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 220 to 14
10-22 19:49:15.524 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.524 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.524 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.534 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.534 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.534 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.544 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.545 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.545 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.545 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 231 to 14
10-22 19:49:15.555 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.555 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.555 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.566 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.566 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.566 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.576 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.576 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.576 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.576 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 242 to 14
10-22 19:49:15.586 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.586 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.586 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.597 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.597 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.597 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.606 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 1
10-22 19:49:15.606 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:15.606 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:15.607 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:15.607 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:15.607 E/ANDR-PERF-TARGET(  474): Eror: Target not initialized
10-22 19:49:15.607 E/ANDR-PERF-OPTSHANDLER(  474): Invalid core no. 0
10-22 19:49:15.607 E/ANDR-PERF-OPTSHANDLER(  474): Request on invalid core or cluster
10-22 19:49:15.607 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [3, 0]
10-22 19:49:15.607 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.607 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.607 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.607 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 1
10-22 19:49:15.607 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:15.607 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:15.607 W/a2dp_sbc_encoder( 1490): a2dp_sbc_get_num_frame_iteration: limiting frames to be sent from 253 to 14
10-22 19:49:15.608 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:15.608 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:15.608 E/ANDR-PERF-TARGET(  474): Eror: Target not initialized
10-22 19:49:15.608 E/ANDR-PERF-OPTSHANDLER(  474): Invalid core no. 0
10-22 19:49:15.608 E/ANDR-PERF-OPTSHANDLER(  474): Request on invalid core or cluster
10-22 19:49:15.608 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [3, 0]
10-22 19:49:15.618 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.618 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.618 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.628 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.628 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.628 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.639 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.639 W/bt_btif_a2dp_source( 1490): btif_a2dp_source_read_callback: UNDERFLOW: ONLY READ 0 BYTES OUT OF 512
10-22 19:49:15.639 W/a2dp_sbc_encoder( 1490): a2dp_sbc_encode_frames: underflow 4, 0
10-22 19:49:15.639 I/bt_btif_a2dp_source( 1490): btif_a2dp_source_audio_tx_stop_event: media_alarm is running, streaming true state=STATE_RUNNING
10-22 19:49:15.649 W/bt_btif ( 1490): poll timeout (10 ms)
10-22 19:49:15.650 W/bt_btif ( 1490): btif_a2dp_command_ack: ## a2dp ack : A2DP_CTRL_CMD_SUSPEND, status 0 ##
10-22 19:49:15.650 W/bt_btif ( 1490): btif_a2dp_data_cb: BTIF MEDIA (A2DP-DATA) EVENT UIPC_CLOSE_EVT
10-22 19:49:15.650 W/bt_btif ( 1490): btif_a2dp_command_ack: ## a2dp ack : A2DP_CTRL_CMD_NONE, status 0 ##
10-22 19:49:15.650 E/bt_btif ( 1490): btif_a2dp_command_ack: warning : no command pending, ignore ack
10-22 19:49:15.650 D/a2dp_sbc_encoder( 1490): a2dp_sbc_feeding_reset: PCM bytes per tick 3528
10-22 19:49:15.650 E/bt_btif_a2dp_source( 1490): btif_a2dp_source_audio_handle_timer: ERROR Media task Scheduled after Suspend
10-22 19:49:15.650 E/bt_btif_a2dp_source( 1490): btif_a2dp_source_audio_handle_timer: ERROR Media task Scheduled after Suspend
10-22 19:49:15.650 I/bt_a2dp_hw(  481): skt_disconnect: fd 12
10-22 19:49:15.650 E/bt_btif_a2dp_source( 1490): btif_a2dp_source_audio_handle_timer: ERROR Media task Scheduled after Suspend
10-22 19:49:15.650 I/chatty  ( 1490): uid=1002(bluetooth) btif_a2dp_sourc identical 10 lines
10-22 19:49:15.650 E/bt_btif_a2dp_source( 1490): btif_a2dp_source_audio_handle_timer: ERROR Media task Scheduled after Suspend
10-22 19:49:15.656 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:15.657 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:15.729 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 1
10-22 19:49:15.729 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:15.729 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:15.730 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:15.730 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:15.730 E/ANDR-PERF-TARGET(  474): Eror: Target not initialized
10-22 19:49:15.730 E/ANDR-PERF-OPTSHANDLER(  474): Invalid core no. 0
10-22 19:49:15.730 E/ANDR-PERF-OPTSHANDLER(  474): Request on invalid core or cluster
10-22 19:49:15.730 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [3, 0]
10-22 19:49:15.730 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 1
10-22 19:49:15.730 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:15.730 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:15.731 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:15.731 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:15.731 E/ANDR-PERF-TARGET(  474): Eror: Target not initialized
10-22 19:49:15.731 E/ANDR-PERF-OPTSHANDLER(  474): Invalid core no. 0
10-22 19:49:15.731 E/ANDR-PERF-OPTSHANDLER(  474): Request on invalid core or cluster
10-22 19:49:15.731 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [3, 0]
10-22 19:49:15.732 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 1
10-22 19:49:15.732 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:15.732 E/ANDR-PERF-TARGET(  474): Error: Invalid logical cluster id 0
10-22 19:49:15.732 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:15.732 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [2, 0]
10-22 19:49:15.732 E/ANDR-PERF-TARGET(  474): Eror: Target not initialized
10-22 19:49:15.732 E/ANDR-PERF-OPTSHANDLER(  474): Invalid core no. 0
10-22 19:49:15.733 E/ANDR-PERF-OPTSHANDLER(  474): Request on invalid core or cluster
10-22 19:49:15.733 E/ANDR-PERF-RESOURCEQS(  474): Failed to apply optimization [3, 0]
10-22 19:49:15.771 D/StatusBar( 1518): disable<e i a s b h r c s > disable2<q i n >
10-22 19:49:15.790 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:15.807 I/chatty  (  483): uid=1000(system) /system/bin/surfaceflinger identical 2 lines
10-22 19:49:15.807 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:15.811 D/StatusBar( 1518): disable<e i a s b h r c s > disable2<q i n >
10-22 19:49:15.823 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:15.873 I/chatty  (  483): uid=1000(system) /system/bin/surfaceflinger identical 6 lines
10-22 19:49:15.874 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:15.954 W/AlarmManager( 1257): Unrecognized alarm listener com.android.server.wifi.WifiConfigStore$1@2426afa
10-22 19:49:15.956 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:15.958 I/chatty  (  483): uid=1000(system) /system/bin/surfaceflinger identical 2 lines
10-22 19:49:15.959 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:15.972 W//vendor/bin/hw/[email protected](  420): Not performing software digesting for symmetric cipher keys
10-22 19:49:15.979 I/chatty  (  420): uid=1000(system) [email protected] identical 4 lines
10-22 19:49:15.979 W//vendor/bin/hw/[email protected](  420): Not performing software digesting for symmetric cipher keys
10-22 19:49:15.980 I/keystore(  723): 1 0
10-22 19:49:16.003 W//vendor/bin/hw/[email protected](  420): Not performing software digesting for symmetric cipher keys
10-22 19:49:16.009 I/chatty  (  420): uid=1000(system) [email protected] identical 4 lines
10-22 19:49:16.009 W//vendor/bin/hw/[email protected](  420): Not performing software digesting for symmetric cipher keys
10-22 19:49:16.011 I/keystore(  723): 1 0
10-22 19:49:16.023 D/WifiConfigStore( 1257): Writing to stores completed in 69 ms.
10-22 19:49:16.292 D/AutofillManager( 1257): No AutofillClient for android on context android.app.ContextImpl@63aa6b6
10-22 19:49:16.324 I/ConfigStore(  483): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
10-22 19:49:16.375 I/ActivityManager( 1257): Killing 2412:com.google.android.gms/u0a38 (adj 100): crash

[mar-v-in]

• Is this on devices with file based encryption?
• Is GmsCore installed on /system or as user app?

[ProjectMoon]

I am experiencing it on a OnePlus 6, an A/B device with file encryption. GmsCore is a system application from LineageOS for MicroG.

[mar-v-in]

I will set up some emulator images for testing (was on my todo regarding #815 anyways). Flashing devices to test various setups is no fun.

[mar-v-in]

Is this with 0.2.8.17785-4 and 0.2.9.19420 only or has anyone seen the same issues with 0.2.8.17785 already? Was the version directly installed on /system or is an older version (0.2.8.17785) installed on /system with update (from F-Droid) on /data?

[ProjectMoon]

This started happening to me after updating to 0.2.9.19420 from F-Droid. The update went over the system app, and then the location service stopped working. Uninstalling the update (and thus returning to the 0.28.17785-4 system app) and rebooting fixed the issue.

[mar-v-in]

@ProjectMoon thanks, that's valuable information. With file based encryption, the updated package will only become available after the password was first entered after boot. The /system location provider is already initialized before, but should handover to the /data location provider as soon as it becomes available. Apparently something is wrong with this handover in UnifiedNlp. So I guess it should be fine as long as we only use GmsCore on /system and don't update through F-Droid on file based encrypted builds until I found what causes this issue.

[Vavun]

I'm not using any type of /data encryption or password protection but I still have this issue.
I tried to update microG directly in system partition to 0.2.9 and remove microG updates, so the only microG gmscore I have in my ROM is placed in /system/priv-app. Problem is still there.

[patrickdrd]

I was able to downgrade using Aurora Droid. It uninstalled the update. Now I'm back on the system version.

damn, I didn't keep the app backup, so now I'm left without location/gps?

any (other) way I can install the previous version?

f-droid downgrade fails..

damn @mar-v-in , this is the 2nd time you're giving me a major headache,
amazingly, I've had less problems with google play services (only once)!

[Iey4iej3]

@patrickdrd There seems no issue for GPS (device only). That is an issue about network based location, UnifiedNlp. And I have a workaround to deal with this issue: just repeatedly disable and enable the backends in UnifiedNlp and it will work dozen of minutes later (I mean, apps will get locations, but still "unregistered" in the self-check). See https://github.com/microg/android_packages_apps_GmsCore/issues/960 and https://github.com/lineageos4microg/android_prebuilts_prebuiltapks/issues/22#issuecomment-544442041 for more details.

[Iey4iej3]

@mar-v-in I believe that for me, the issue starts when I flashed the LOS for microG once builds for Xiaomi devices resumed, with 0.2.8.17785-4 (06c8b76) which is unavailable on F-Droid. I do have an encrypted backup with LOS for microG built in July, but ridiculously when I wanted to try to restore to re-check that UnifiedNlp works in the July build, there is a bug in TWRP so that I can no longer decrypt encrypted backups... Many bugs of many different softwares come together at once!

[patrickdrd]

@patrickdrd There seems no issue for GPS (device only). That is an issue about network based location, UnifiedNlp. And I have a workaround to deal with this issue: just repeatedly disable and enable the backends in UnifiedNlp and it will work dozen of minutes later (I mean, apps will get locations, but still "unregistered" in the self-check). See #960 and lineageos4microg/android_prebuilts_prebuiltapks#22 (comment) for more details.

could be, but gms crashed immediately after I turned on location so I had to reboot device in order for notifications to work!

[Iey4iej3]

@patrickdrd I think it is better to catch a log and post another issue. Might be related to https://github.com/microg/android_packages_apps_GmsCore/issues/961#issuecomment-545055531, but it is really a different issue than the main post, so it might be better to classify the issues properly so that one is not complicatedly intertwined with another.

[EchedeyLR]

Same issue here with UnifiedNLP unchecked in 5 different mobile phones between LineageOS from microG 14.1 and 16.

EDITED: 0.2.9 build

[Iey4iej3]

@EchedeyLR Could you please extract the values of config_enableNetworkLocationOverlay, config_enableFusedLocationOverlay and config_locationProviderPackageNames, from both adb pull /system/framework/framework-res.apk and adb pull /system/vendor/overlay/framework-res__auto_generated_rro.apk with aids of apktool (that is, use apktool to extract these .apk files then search recursively by, say, grep -R, these values)?

[k3lt]

Same here, started happening after updating to 0.2.9.19420 with F-Droid.

[EchedeyLR]

@EchedeyLR Could you please extract the values of config_enableNetworkLocationOverlay, config_enableFusedLocationOverlay and config_locationProviderPackageNames, from _both_ adb pull /system/framework/framework-res.apk and adb pull /system/vendor/overlay/framework-res__auto_generated_rro.apk with aids of apktool (that is, use apktool to extract these .apk files then search recursively by, say, grep -R, these values)?

/system/vendor/overlay directory does not exist at least in the BQ Aquaris U Plus

[Iey4iej3]

@EchedeyLR Do you have vendor directory in /system or the root directory /? If so, which probably means that there is no vendor overlay, then just decode /system/framework/framework-res.apk.

[EchedeyLR]

@EchedeyLR Do you have vendor directory in /system or the root directory /? If so, which probably means that there is no vendor overlay, then just decode /system/framework/framework-res.apk.

It is on both, maybe one is a symbolic link of the other. And yes /system/framework/framework-res.apk is perfectly.

[EchedeyLR]

NetworkLocationOverlay^@!!config_enable
onfig_locationProviderPackageNames^@^\^\ (I dont know the result here, after that is just config_testLocationProviders^@^\^)

[Iey4iej3]

@mar-v-in The issue @Vavun mentioned should have been a compile-time error, according to the document:

public class AbstractMethodError extends IncompatibleClassChangeError Thrown when an application tries to call an abstract method. Normally, this error is caught by the compiler; this error can only occur at run time if the definition of some class has incompatibly changed since the currently executing method was last compiled.

Maybe you need to delete the old compiled objects and rebuild the package.

[Iey4iej3]

@EchedeyLR You need apktool to decode the package. First run with parameter if to install, then d to decode. (I am also new so I am not sure whether this is correct. Hopefully experts could explain. But at least it works for me). https://ibotpeaches.github.io/Apktool/documentation/

[EchedeyLR]

@EchedeyLR You need apktool to decode the package. First run with parameter if to install, then d to decode. (I am also new so I am not sure whether this is correct. Hopefully experts could explain. But at least it works for me).

Oh thx. I am trying to decode directly. Didn't know I needed that. I just knew that .apk extension was other for a common archive file (I dont know if was zip under deflate or what)

[EchedeyLR]

config_enableNetworkLocationOverlay
true

config_locationProviderPackageNames
com.google.android.gms
com.android.location.fused

[chris42]

@ProjectMoon thanks, that's valuable information. With file based encryption, the updated package will only become available after the password was first entered after boot. The /system location provider is already initialized before, but should handover to the /data location provider as soon as it becomes available. Apparently something is wrong with this handover in UnifiedNlp. So I guess it should be fine as long as we only use GmsCore on /system and don't update through F-Droid on file based encrypted builds until I found what causes this issue.

@mar-v-in I am experiencing the same crash and error on a fully unencrypted device. Also did the update via the f-droid repository.

[EchedeyLR]

@ProjectMoon thanks, that's valuable information. With file based encryption, the updated package will only become available after the password was first entered after boot. The /system location provider is already initialized before, but should handover to the /data location provider as soon as it becomes available. Apparently something is wrong with this handover in UnifiedNlp. So I guess it should be fine as long as we only use GmsCore on /system and don't update through F-Droid on file based encrypted builds until I found what causes this issue.

@mar-v-in I am experiencing the same crash and error on a fully unencrypted device. Also did the update via the f-droid repository.

All my devices are not encrypted.

[Iey4iej3]

@chris42 Maybe it's better to open another issue about the crash issue (link it with https://github.com/microg/android_packages_apps_GmsCore/issues/961#issuecomment-545055531 and https://github.com/microg/android_packages_apps_GmsCore/issues/961#issuecomment-545483240). This post is devoted to the unregistering issue, and the post you quoted is apparently about that. I think that mixing different issues in a same post will confuse developers and others (the meaning of "same here" is very unclear now), especially about to determine a definitive list of issues to resolve.

[Iey4iej3]

@EchedeyLR I don't know whether you are suffering from the issue of UnifiedNlp not being registered, or crashing. For the later, as above, I suggest to create another post for this. If it is about the former, the sad thing is that there are several reasons leading to the same phenomenon. Your framework-res.apk seems to be correctly set. Mine is not, and it is very probable that mine is due to that setting...

[EchedeyLR]

Sorry, I was replying to the first post. I have issues with not being registered. No crashing itself.

[nift4]

Using unoffical LineageOS (not for microG!) and since the update the system can't register UnifiedNlp (the integrated GmsCore version).

[EchedeyLR]

Using unoffical LineageOS (not for microG!) and since the update the system can't register UnifiedNlp (the integrated GmsCore version).

The LineageOS image version or you meant the microG version?

[On3simos]

Same bug here on Lineage 14.1. 0.2.7 version was working fine. I updated to 0.2.9 using F-Droid and on reboot I had a message error (MicroG stopped working), and then on the self-check UnifiedNlp appeared as not registered in the system (but strangely it did work, as I managed to get my position on the GPS using only the network location). Several reboots did not solve the issue. I tried to uninstall Core service and reinstall it from scratch, but it did not solve the issue. I could not downgrade using F-Droid, so I had to make a clean install of 0.2.7, and now it works again.

What new feature 0.2.9 was supposed to bring ? I mean, is there any security issue if I continue using the old version ? If it is not the case, I will simply use 0.2.7 until the bug is solved.

[patrickdrd]

0.2.8-4 works fine too

[On3simos]

Yes, my mistake. I was on the non-mapbox version, so F-droid did not show me the 0.2.8 version, which appears only as mapbox, and only showed me 0.2.9 as an elligilible update. But 0.2.8 works fine as well.

[Iey4iej3]

@On3simos @patrickdrd Let's continue the crash issue at https://github.com/microg/android_packages_apps_GmsCore/issues/964

[nift4]

The LineageOS image version or you meant the microG version?

microG version via F-Droid

[nift4]

@On3simos @patrickdrd Let's continue the crash issue at #964

Having also an crash on boot

[IzzySoft]

For those having the issue after update via F-Droid (like me), but don't want to fiddle in the directories manually with root powers:

• Download 0.2.8 from releases
• adb shell cmd package uninstall -k com.google.android.gms (resets to "factory version")
• adb install -r GmsCore-v0.2.8.17785-mapbox.apk

Unfortunately (for me at least) the -k wasn't honored it seems, so I had to enable the backends again manually (these were the only changes I made before, not using any Google account/FCM etc).

Of course, restoring the last TiBu backup should work as well :grin:

[skulko]

I had no problem with previous version but with microG-0.2.9.1420 i have crashes during setup wizard and unifiednlp is not registered in system.
microG is integrated in ROM here. no encryption

[ProjectMoon]

@mar-v-in if MicroG as a system app is upgraded to 0.29 as part of an OTA update, will it still have this issue?

[moritztk]

The only way to prevent micro g from crashing right now is to freshly install Lineage+MicroG and NOT activate "Use Wi-Fi and cellular networks" during setup

[Iey4iej3]

@moritztk Did you see a new build at https://github.com/microg/android_packages_apps_GmsCore/issues/964#issuecomment-546473162 which should fix the issue?

[moritztk]

I didnt, thank you, it works!

[EchedeyLR]

Updated to 2.10 and not it works correctly.

[IzzySoft]

@Iey4iej3 @Vavun I guess this issue can be closed as solved now?

[nift4]

From me yes