Gmscore: Getting crash sometimes on GCM page

[Vavun]

Usualy I get this crash when doing something on Google Cloud Messaging page.
Don't know what the reason
Maybe @voidstarstar could be so kind to figure out the reason

This is nanolx fork, but I got the same on vanilla

04-01 20:46:48.429 I/ActivityManager(1332): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.google.android.gms/org.microg.gms.ui.SettingsActivityLauncher bnds=[158,523][293,718]} from uid 10145
04-01 20:46:49.985 I/ActivityManager(1332): START u0 {cmp=com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity} from uid 10033
04-01 20:46:50.057 V/ResourceType(2524): Too many attribute references, stopped at: 0x01010034
04-01 20:46:50.057 V/ResourceType(2524): Too many attribute references, stopped at: 0x01010034
04-01 20:46:50.325 V/ResourceType(2524): ResTable_typeSpec entry count inconsistent: given 556, previously 557
04-01 20:46:50.326 V/ResourceType(2524): ResTable_typeSpec entry count inconsistent: given 556, previously 557
04-01 20:46:50.326 V/ResourceType(2524): ResTable_typeSpec entry count inconsistent: given 234, previously 262
04-01 20:46:50.326 V/ResourceType(2524): ResTable_typeSpec entry count inconsistent: given 463, previously 467
04-01 20:46:50.326 V/ResourceType(2524): ResTable_typeSpec entry count inconsistent: given 291, previously 557
04-01 20:46:50.326 V/ResourceType(2524): ResTable_typeSpec entry count inconsistent: given 130, previously 528
04-01 20:46:50.349 V/ResourceType(2524): ResTable_typeSpec entry count inconsistent: given 440, previously 485
04-01 20:46:50.350 V/ResourceType(2524): ResTable_typeSpec entry count inconsistent: given 440, previously 485
04-01 20:46:50.351 V/ResourceType(2524): ResTable_typeSpec entry count inconsistent: given 266, previously 273
04-01 20:46:50.351 V/ResourceType(2524): ResTable_typeSpec entry count inconsistent: given 198, previously 681
04-01 20:46:50.390 I/zygote64(2524): Do full code cache collection, code=2014KB, data=1297KB
04-01 20:46:50.399 I/zygote64(2524): After code cache collection, code=1999KB, data=1172KB
04-01 20:46:50.620 I/Choreographer(2524): Skipped 36 frames!  The application may be doing too much work on its main thread.
04-01 20:46:50.687 V/chatty  (1332): uid=1000(system) batterystats-wo expire 6 lines
04-01 20:46:50.749 V/ANDR-PERF-MPCTL(502): Invalid profile no. 0, total profiles 0 only
04-01 20:46:52.187 V/ANDR-PERF-MPCTL(502): Invalid profile no. 0, total profiles 0 only
04-01 20:46:55.404 D/AndroidRuntime(2524): Shutting down VM
04-01 20:46:55.412 E/AndroidRuntime(2524): FATAL EXCEPTION: main
04-01 20:46:55.412 E/AndroidRuntime(2524): Process: com.google.android.gms, PID: 2524
04-01 20:46:55.412 E/AndroidRuntime(2524): java.lang.IllegalStateException: Cannot call this method while RecyclerView is computing a layout or scrolling android.support.v7.widget.RecyclerView{7b700d2 VFED.V... .F...... 0,0-720,1042 #7f080062 app:id/list}, adapter:com.takisoft.fix.support.v7.preference.PreferenceGroupAdapter@f9785a3, layout:android.support.v7.widget.LinearLayoutManager@d4dc0a0, context:android.view.ContextThemeWrapper@68bf459
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.RecyclerView.assertNotInLayoutOrScroll(Unknown Source:31)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.RecyclerView$RecyclerViewDataObserver.onItemRangeChanged(Unknown Source:3)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.RecyclerView$AdapterDataObservable.notifyItemRangeChanged(Unknown Source:18)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.RecyclerView$Adapter.notifyItemChanged(Unknown Source:3)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.preference.PreferenceGroupAdapter.onPreferenceChange(Unknown Source:9)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.preference.Preference.notifyChanged(Unknown Source:6)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.preference.Preference.setSummary(Unknown Source:18)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at org.microg.gms.ui.GcmFragment$GcmAppPreference.updateViewDetails(Unknown Source:69)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at org.microg.gms.ui.GcmFragment$GcmAppPreference.onBindViewHolder(Unknown Source:0)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.preference.PreferenceGroupAdapter.onBindViewHolder(Unknown Source:4)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.preference.PreferenceGroupAdapter.onBindViewHolder(Unknown Source:2)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.RecyclerView$Adapter.onBindViewHolder(Unknown Source:0)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.RecyclerView$Adapter.bindViewHolder(Unknown Source:29)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.RecyclerView$Recycler.tryBindViewHolderByDeadline(Unknown Source:40)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.RecyclerView$Recycler.tryGetViewHolderForPositionByDeadline(Unknown Source:513)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.GapWorker.prefetchPositionWithDeadline(Unknown Source:14)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.GapWorker.flushTaskWithDeadline(Unknown Source:15)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.GapWorker.flushTasksWithDeadline(Unknown Source:22)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.GapWorker.prefetch(Unknown Source:3)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.support.v7.widget.GapWorker.run(Unknown Source:79)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.os.Handler.handleCallback(Handler.java:790)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.os.Handler.dispatchMessage(Handler.java:99)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.os.Looper.loop(Looper.java:164)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at android.app.ActivityThread.main(ActivityThread.java:6494)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at java.lang.reflect.Method.invoke(Native Method)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:440)
04-01 20:46:55.412 E/AndroidRuntime(2524):  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:807)
04-01 20:46:55.423 W/ActivityManager(1332):   Force finishing activity com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity
04-01 20:46:55.436 I/ActivityManager(1332): Showing crash dialog for package com.google.android.gms u0
04-01 20:46:55.482 V/chatty  (1332): uid=1000(system) android.ui expire 5 lines
04-01 20:46:55.495 V/chatty  (1332): uid=1000(system) RenderThread expire 4 lines
04-01 20:46:55.927 W/ActivityManager(1332): Activity pause timeout for ActivityRecord{bab6ba3 u0 com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity t14625 f}
04-01 20:47:00.483 D/AutofillManagerService(1332): Close system dialogs
04-01 20:47:00.485 W/ActivityManager(1332):   Force finishing activity com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity
04-01 20:47:00.508 W/ActivityManager(1332):   Force finishing activity com.google.android.gms/org.microg.gms.ui.SettingsActivityLauncher
04-01 20:47:00.612 I/ActivityManager(1332): Killing 2524:com.google.android.gms/u0a33 (adj 100): crash
04-01 20:47:00.623 I/cr_ChildProcessService(3858): Destroying ChildProcessService pid=3858
04-01 20:47:00.633 W/ActivityManager(1332): Unable to start service Intent { act=com.android.launcher3.WINDOW_OVERLAY dat=app://ru.whatau.cpl:10145?v=7&cv=9 pkg=com.google.android.apps.nexuslauncher } U=0: not found
04-01 20:47:00.633 W/Looper  (1332): Dispatch took 149ms on android.ui, h=Handler (com.android.server.am.AppErrorDialog$1) {af50b48} cb=null msg=7
04-01 20:47:00.636 I//system/bin/webview_zygote64(3858): System.exit called, status: 0
04-01 20:47:00.636 I/AndroidRuntime(3858): VM exiting with result code 0, cleanup skipped.
04-01 20:47:00.651 I/ActivityManager(1332): Killing 3858:com.android.webview:sandboxed_process0/u0a33i0 (adj 0): isolated not needed
04-01 20:47:00.651 I/ActivityManager(1332): Killing 3174:android.ext.services/u0a24 (adj 906): empty #31
04-01 20:47:00.653 W/AppOps  (1332): Finishing op nesting under-run: uid 1000 pkg android code 24 time=0 duration=0 nesting=0
04-01 20:47:00.657 E/SurfaceFlinger(514): Failed to find layer (Application Error: com.google.android.gms#0) in layer parent (no-parent).
04-01 20:47:00.707 D/KernelUidCpuTimeReader(1332): Removing uids 99000-99000
04-01 20:47:00.763 D/ConnectivityService(1332): ConnectivityService NetworkRequestInfo binderDied(NetworkRequest [ LISTEN id=14, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED] ], android.os.BinderProxy@deb62bf)
04-01 20:47:00.764 W/ActivityManager(1332): setHasOverlayUi called on unknown pid: 2524
04-01 20:47:01.208 W/OpenGLRenderer(2351): Incorrectly called buildLayer on View: ShortcutAndWidgetContainer, destroying layer...
04-01 20:47:03.282 V/ANDR-PERF-MPCTL(502): Invalid profile no. 0, total profiles 0 only

[voidstarstar]

Well I'm certainly no expert, but I can try.

I'm having trouble replicating the crash. Can you be as specific as possible how you were able to trigger it?

[Vavun]

I can't get exact reason, but I'll try to catch it and collect more logs. Thanks.

[Vavun]

@voidstarstar I GOT IT!
Steps to reproduce (bad english) :

• Install some app that uses GCM or FCM (I took SystemUI tuner as example)
• Launch it once so it register itself as cloud messages receiver
• Chech app registration status in microG => GCM
• Uninstall app
• Chech app registration status in microG => GCM and you see the ghost of removed app
• Try to unregister ghost
• Crash

It doesn't happen every time you trying unregister ghost

This is screencast and log recorded at the same time
Screencast https://youtu.be/2w19UryyWTw
Log 2019-04-01-23-44-45.txt

[voidstarstar]

@Vavun Thanks, those steps were very helpful.

For reference, in your last log, I think it appears at time 04-01 23:45:53.793. It sounds like this could be related to the patch in #737, but that can't be the case if it occurs in a vanilla (official v0.2.6.13280?) build too.

I can now reproduce a crash, but it seems slightly different than yours. I don't see a ghost app in the GCM page, but I can still get a similar crash as follows:

• Install an app that uses GCM.
• Launch it once so it registers.
• Check app registration status in microG and _also tap on it to open the settings page_.
• Uninstall the app.
• Go back to microG, which should still be on the settings page for the uninstalled app.
• MicroG instantly crashes.

My bug seems to occur due to this line reading a null reference. I wonder if this is related.

[Vavun]

@voidstarstar OK, I'll try to reproduce this on vanilla on other device.
maybe this will clear up something

[Vavun]

Can't reproduce on other device with vanilla and with fork. Strange.

[voidstarstar]

Weird, so the same version caused the bug on one device but not on another? I wasn't able to get a ghost entry to appear on my device either.

At least this bug doesn't seem super critical to daily usage.

[Vavun]

So I did some research.
The problem could be reproduced only with fork now.

Considering I can not reproduce this on vanilla I close the issue

Thanks for your help =)

PS

This is a part of log when I get random crash just for history and future research maybe
Steps to reproduce:

• Install app with GCM
• Switch to previously opened microG => GCM page

(happens infrequently)

And yes, ghost apps in GCM appear only on fork

04-02 11:49:58.774 I/ActivityManager(1294): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10200000 cmp=ru.whatau.cpl/com.google.android.apps.nexuslauncher.NexusLauncherActivity (has extras)} from uid 1000
04-02 11:49:58.788 D/GmsMeasureSvcImpl(2452): onTransact [unknown]: 10, android.os.Parcel@a05e6a8, 16
04-02 11:49:58.813 W/ActivityManager(1294): Unable to start service Intent { act=com.android.launcher3.WINDOW_OVERLAY dat=app://ru.whatau.cpl:10145?v=7&cv=9 pkg=com.google.android.apps.nexuslauncher } U=0: not found
04-02 11:49:59.261 I/zygote64(1294): NativeAlloc concurrent copying GC freed 28304(1548KB) AllocSpace objects, 16(560KB) LOS objects, 42% free, 10MB/19MB, paused 196us total 162.513ms
04-02 11:49:59.364 W/OpenGLRenderer(2352): Incorrectly called buildLayer on View: ShortcutAndWidgetContainer, destroying layer...
04-02 11:49:59.719 I/QcrilOemhookMsgTunnel(1917): [0]processOemHookIndication length=21
04-02 11:49:59.719 D/QcrilMsgTunnelIfaceManager(1917): handleMessage what = 0
04-02 11:50:00.350 V/ANDR-PERF-MPCTL(501): Invalid profile no. 0, total profiles 0 only
04-02 11:50:02.279 D/LoaderTask(2352): loadWorkspace: loading default favorites
04-02 11:50:03.909 V/ANDR-PERF-MPCTL(501): Invalid profile no. 0, total profiles 0 only
04-02 11:50:04.233 I/ActivityManager(1294): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.google.android.gms/org.microg.gms.ui.SettingsActivityLauncher bnds=[158,523][293,718]} from uid 10145
04-02 11:50:04.286 W/WindowManager(1294): removeWindowToken: Attempted to remove non-existing token: android.os.Binder@51c17d2
04-02 11:50:04.608 I/zygote64(2452): Do partial code cache collection, code=28KB, data=23KB
04-02 11:50:04.608 I/zygote64(2452): After code cache collection, code=28KB, data=23KB
04-02 11:50:04.608 I/zygote64(2452): Increasing code cache capacity to 128KB
04-02 11:50:04.807 D/OpenGLRenderer(2452): HWUI GL Pipeline
04-02 11:50:04.873 I/Adreno  (2452): QUALCOMM build                   : 624c5bb, I109c45a694
04-02 11:50:04.873 I/Adreno  (2452): Build Date                       : 05/22/18
04-02 11:50:04.873 I/Adreno  (2452): OpenGL ES Shader Compiler Version: EV031.22.00.01_06
04-02 11:50:04.873 I/Adreno  (2452): Local Branch                     : 
04-02 11:50:04.873 I/Adreno  (2452): Remote Branch                    : 
04-02 11:50:04.873 I/Adreno  (2452): Remote Branch                    : 
04-02 11:50:04.873 I/Adreno  (2452): Reconstruct Branch               : 
04-02 11:50:04.884 I/Adreno  (2452): PFP: 0x005ff087, ME: 0x005ff063
04-02 11:50:04.894 I/com.google.android.gms(2452): android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasWideColorDisplay retrieved: 0
04-02 11:50:04.896 I/OpenGLRenderer(2452): Initialized EGL, version 1.4
04-02 11:50:04.896 D/OpenGLRenderer(2452): Swap behavior 2
04-02 11:50:04.998 I/zygote64(2452): Do partial code cache collection, code=61KB, data=47KB
04-02 11:50:04.999 I/zygote64(2452): After code cache collection, code=61KB, data=47KB
04-02 11:50:04.999 I/zygote64(2452): Increasing code cache capacity to 256KB
04-02 11:50:05.258 I/zygote64(2452): Compiler allocated 7MB to compile void android.widget.TextView.<init>(android.content.Context, android.util.AttributeSet, int, int)
04-02 11:50:05.341 I/zygote64(2452): Do full code cache collection, code=107KB, data=93KB
04-02 11:50:05.342 I/zygote64(2452): After code cache collection, code=57KB, data=43KB
04-02 11:50:05.379 I/ActivityManager(1294): Displayed com.google.android.gms/org.microg.gms.ui.SettingsActivityLauncher: +1s101ms
04-02 11:50:06.147 I/ActivityManager(1294): START u0 {cmp=com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity} from uid 10088
04-02 11:50:06.284 V/ResourceType(2452): Too many attribute references, stopped at: 0x01010034
04-02 11:50:06.284 V/ResourceType(2452): Too many attribute references, stopped at: 0x01010034
04-02 11:50:06.713 I/zygote64(2452): Do partial code cache collection, code=124KB, data=82KB
04-02 11:50:06.714 I/zygote64(2452): After code cache collection, code=124KB, data=81KB
04-02 11:50:06.714 I/zygote64(2452): Increasing code cache capacity to 512KB
04-02 11:50:07.040 V/ResourceType(2452): ResTable_typeSpec entry count inconsistent: given 440, previously 485
04-02 11:50:07.040 V/ResourceType(2452): ResTable_typeSpec entry count inconsistent: given 440, previously 485
04-02 11:50:07.041 V/ResourceType(2452): ResTable_typeSpec entry count inconsistent: given 266, previously 273
04-02 11:50:07.041 V/ResourceType(2452): ResTable_typeSpec entry count inconsistent: given 198, previously 681
04-02 11:50:07.146 V/ResourceType(2452): ResTable_typeSpec entry count inconsistent: given 556, previously 557
04-02 11:50:07.147 V/ResourceType(2452): ResTable_typeSpec entry count inconsistent: given 556, previously 557
04-02 11:50:07.148 V/ResourceType(2452): ResTable_typeSpec entry count inconsistent: given 234, previously 262
04-02 11:50:07.148 V/ResourceType(2452): ResTable_typeSpec entry count inconsistent: given 463, previously 467
04-02 11:50:07.148 V/ResourceType(2452): ResTable_typeSpec entry count inconsistent: given 291, previously 557
04-02 11:50:07.148 V/ResourceType(2452): ResTable_typeSpec entry count inconsistent: given 130, previously 528
04-02 11:50:07.331 I/Finsky  (3352): [2] com.google.android.finsky.scheduler.JobSchedulerEngine$PhoneskyJobSchedulerJobService.onStartJob(3): onJobSchedulerWakeup with jobId 9001
04-02 11:50:07.334 I/Finsky  (3352): [2] pqc.a(21): Scheduling fallback job with id: 9034, and delay: 43200000 ms
04-02 11:50:07.338 I/Finsky  (3352): [2] pqc.a(5): Scheduling fallback in 64799998 (absolute: 64895277)
04-02 11:50:07.390 I/Finsky  (3352): [2] pqk.handleMessage(10): DeviceState: DeviceState{currentTime=1554195007385, isCharging=true, isIdle=false, netAny=true, netNotRoaming=true, netUnmetered=true}
04-02 11:50:07.396 I/Finsky  (3352): [80] prq.b(7): Jobs in database: 1-1337 12-1 24-77777777 26-1414141414 
04-02 11:50:07.400 I/Finsky  (3352): [2] pqf.a(45): Running job: 12-1
04-02 11:50:07.402 I/Finsky  (3352): [2] com.google.android.finsky.contentsync.ContentSyncJob.a(17): ContentSyncJob started
04-02 11:50:07.403 I/Finsky  (3352): [2] pqk.handleMessage(81): RunningQueue size: 1, PendingQueue size: 0
04-02 11:50:07.404 I/Finsky  (3352): [2] pqk.handleMessage(90): Running queue: 12-1 
04-02 11:50:07.536 I/Finsky  (3352): [41] dmk.a(4): Completed 0 account content syncs with 0 successful.
04-02 11:50:07.537 I/Finsky  (3352): [2] com.google.android.finsky.contentsync.ContentSyncJob.a(15): Installation state replication succeeded.
04-02 11:50:07.538 I/Finsky  (3352): [2] prl.b(15): jobFinished: 12-1. TimeElapsed: 137ms
04-02 11:50:07.539 I/Finsky  (3352): [2] pqf.a(10): Job 12-1 finished
04-02 11:50:07.540 I/Finsky  (3352): [2] pqk.handleMessage(81): RunningQueue size: 0, PendingQueue size: 0
04-02 11:50:07.540 I/Finsky  (3352): [2] pqk.handleMessage(27): Executor finished
04-02 11:50:07.549 I/Finsky  (3352): [2] prq.b(7): Jobs in database: 1-1337 24-77777777 26-1414141414 
04-02 11:50:07.550 I/Finsky  (3352): [2] ppj.a(66): ConstraintMapping: 24-77777777,  -> L: 0ms, D: 51818414ms, C: false, I: true, N: 0
04-02 11:50:07.551 I/Finsky  (3352): [2] ppj.a(66): ConstraintMapping: 1-1337,  -> L: 12202242ms, D: 55402242ms, C: false, I: false, N: 1
04-02 11:50:07.552 I/Finsky  (3352): [2] ppj.a(66): ConstraintMapping: 26-1414141414,  -> L: 13458225ms, D: 14358225ms, C: false, I: false, N: 0
04-02 11:50:07.556 I/Finsky  (3352): [2] com.google.android.finsky.scheduler.JobSchedulerEngine.a(40): Cancelling existing job with id: 9000
04-02 11:50:07.558 I/Finsky  (3352): [2] com.google.android.finsky.scheduler.JobSchedulerEngine.a(40): Cancelling existing job with id: 9002
04-02 11:50:07.560 I/Finsky  (3352): [2] com.google.android.finsky.scheduler.JobSchedulerEngine.a(14): Throttling wakeup for job 9003 (expected to run in 0 ms) due to recent wakeup
04-02 11:50:07.561 I/Finsky  (3352): [2] com.google.android.finsky.scheduler.JobSchedulerEngine.a(4): Scheduling job Id: 9003, L: 30000, D: 51818414, C: false, I: true, N: 0
04-02 11:50:07.563 I/Finsky  (3352): [2] com.google.android.finsky.scheduler.JobSchedulerEngine.a(4): Scheduling job Id: 9004, L: 12202242, D: 55402242, C: false, I: false, N: 1
04-02 11:50:07.565 I/Finsky  (3352): [2] com.google.android.finsky.scheduler.JobSchedulerEngine.a(4): Scheduling job Id: 9005, L: 13458225, D: 14358225, C: false, I: false, N: 0
04-02 11:50:07.619 I/Choreographer(2452): Skipped 86 frames!  The application may be doing too much work on its main thread.
04-02 11:50:07.811 I/zygote64(2452): Do full code cache collection, code=250KB, data=160KB
04-02 11:50:07.812 I/zygote64(2452): After code cache collection, code=234KB, data=123KB
04-02 11:50:07.991 I/zygote64(2452): Do partial code cache collection, code=236KB, data=134KB
04-02 11:50:07.991 I/zygote64(2452): After code cache collection, code=236KB, data=134KB
04-02 11:50:07.991 I/zygote64(2452): Increasing code cache capacity to 1024KB
04-02 11:50:08.249 I/ActivityManager(1294): Displayed com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity: +2s72ms
04-02 11:50:09.390 D/AndroidRuntime(2452): Shutting down VM
--------- beginning of crash
04-02 11:50:09.402 E/AndroidRuntime(2452): FATAL EXCEPTION: main
04-02 11:50:09.402 E/AndroidRuntime(2452): Process: com.google.android.gms, PID: 2452
04-02 11:50:09.402 E/AndroidRuntime(2452): java.lang.IllegalStateException: Cannot call this method while RecyclerView is computing a layout or scrolling android.support.v7.widget.RecyclerView{ad75fa1 VFED.V... .F...... 0,0-720,1042 #7f080062 app:id/list}, adapter:com.takisoft.fix.support.v7.preference.PreferenceGroupAdapter@45cf5c6, layout:android.support.v7.widget.LinearLayoutManager@b9b2987, context:android.view.ContextThemeWrapper@d2200b4
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.RecyclerView.assertNotInLayoutOrScroll(RecyclerView.java:2769)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.RecyclerView$RecyclerViewDataObserver.onItemRangeChanged(RecyclerView.java:5188)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.RecyclerView$AdapterDataObservable.notifyItemRangeChanged(RecyclerView.java:11800)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.RecyclerView$Adapter.notifyItemChanged(RecyclerView.java:7005)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.preference.PreferenceGroupAdapter.onPreferenceChange(PreferenceGroupAdapter.java:276)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.preference.Preference.notifyChanged(Preference.java:1208)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.preference.Preference.setSummary(Preference.java:782)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at org.microg.gms.ui.GcmFragment$GcmAppPreference.updateViewDetails(GcmFragment.java:207)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at org.microg.gms.ui.GcmFragment$GcmAppPreference.onBindViewHolder(GcmFragment.java:216)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.preference.PreferenceGroupAdapter.onBindViewHolder(PreferenceGroupAdapter.java:376)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.preference.PreferenceGroupAdapter.onBindViewHolder(PreferenceGroupAdapter.java:44)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.RecyclerView$Adapter.onBindViewHolder(RecyclerView.java:6673)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.RecyclerView$Adapter.bindViewHolder(RecyclerView.java:6714)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.RecyclerView$Recycler.tryBindViewHolderByDeadline(RecyclerView.java:5647)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.RecyclerView$Recycler.tryGetViewHolderForPositionByDeadline(RecyclerView.java:5913)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.GapWorker.prefetchPositionWithDeadline(GapWorker.java:285)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.GapWorker.flushTaskWithDeadline(GapWorker.java:342)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.GapWorker.flushTasksWithDeadline(GapWorker.java:358)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.GapWorker.prefetch(GapWorker.java:365)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.support.v7.widget.GapWorker.run(GapWorker.java:396)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.os.Handler.handleCallback(Handler.java:790)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.os.Handler.dispatchMessage(Handler.java:99)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.os.Looper.loop(Looper.java:164)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at android.app.ActivityThread.main(ActivityThread.java:6494)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at java.lang.reflect.Method.invoke(Native Method)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:440)
04-02 11:50:09.402 E/AndroidRuntime(2452):  at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:807)
04-02 11:50:09.410 W/ActivityManager(1294):   Force finishing activity com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity
04-02 11:50:09.425 I/ActivityManager(1294): Showing crash dialog for package com.google.android.gms u0
04-02 11:50:09.481 V/StaticLayout(1294): maxLineHeight should not be -1.  maxLines:2 lineCount:2
04-02 11:50:09.485 V/chatty  (1294): uid=1000(system) android.ui identical 3 lines
04-02 11:50:09.485 V/StaticLayout(1294): maxLineHeight should not be -1.  maxLines:2 lineCount:2
04-02 11:50:09.493 I/OpenGLRenderer(1294): Initialized EGL, version 1.4
04-02 11:50:09.493 D/OpenGLRenderer(1294): Swap behavior 2
04-02 11:50:09.688 W/Looper  (1294): Dispatch took 193ms on android.ui, h=Handler (android.view.Choreographer$FrameHandler) {6b6bdc1} cb=android.view.Choreographer$FrameDisplayEventReceiver@de5c966 msg=0
04-02 11:50:09.913 W/ActivityManager(1294): Activity pause timeout for ActivityRecord{981cf5 u0 com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity t15111 f}
04-02 11:50:14.918 I/InputDispatcher(1294): Application is not responding: Window{169465c u0 com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity}.  It has been 5497.8ms since event, 5003.4ms since wait started.  Reason: Waiting to send non-key event because the touched window has not finished processing certain input events that were delivered to it over 500.0ms ago.  Wait queue length: 8.  Wait queue head age: 5579.9ms.
04-02 11:50:14.954 I/WindowManager(1294): Input event dispatching timed out sending to com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity.  Reason: Waiting to send non-key event because the touched window has not finished processing certain input events that were delivered to it over 500.0ms ago.  Wait queue length: 8.  Wait queue head age: 5579.9ms.
04-02 11:50:15.048 I/ActivityManager(1294): Crashing app skipping ANR: ProcessRecord{d9ba3c8 2452:com.google.android.gms/u0a88} Input dispatching timed out (Waiting to send non-key event because the touched window has not finished processing certain input events that were delivered to it over 500.0ms ago.  Wait queue length: 8.  Wait queue head age: 5579.9ms.)
04-02 11:50:15.865 D/AutofillManagerService(1294): Close system dialogs
04-02 11:50:15.866 W/ActivityManager(1294):   Force finishing activity com.google.android.gms/org.microg.gms.ui.GcmFragment$AsActivity
04-02 11:50:15.890 W/ActivityManager(1294):   Force finishing activity com.google.android.gms/org.microg.gms.ui.SettingsActivityLauncher
04-02 11:50:15.942 E/ActivityManager(1294): Found activity ActivityRecord{502a5cc u0 com.google.android.gms/org.microg.gms.ui.SettingsActivityLauncher t-1 f} in proc activity list using null instead of expected ProcessRecord{d9ba3c8 2452:com.google.android.gms/u0a88}
04-02 11:50:15.978 I/ActivityManager(1294): Killing 2452:com.google.android.gms/u0a88 (adj 100): crash
04-02 11:50:15.979 W/system_server(1294): kill(-2452, 9) failed: No such process
04-02 11:50:15.987 W/Looper  (1294): Dispatch took 122ms on android.ui, h=Handler (com.android.server.am.AppErrorDialog$1) {dd6174c} cb=null msg=7
04-02 11:50:15.989 I/ActivityManager(1294): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10200000 cmp=ru.whatau.cpl/com.google.android.apps.nexuslauncher.NexusLauncherActivity (has extras)} from uid 1000
04-02 11:50:16.001 W/ActivityManager(1294): Unable to start service Intent { act=com.android.launcher3.WINDOW_OVERLAY dat=app://ru.whatau.cpl:10145?v=7&cv=9 pkg=com.google.android.apps.nexuslauncher } U=0: not found
04-02 11:50:16.010 W/AppOps  (1294): Finishing op nesting under-run: uid 1000 pkg android code 24 time=0 duration=0 nesting=0
04-02 11:50:16.015 V/chatty  (1294): uid=1000(system) ActivityManager identical 1 line
04-02 11:50:16.061 W/system_server(1294): kill(-2452, 9) failed: No such process
04-02 11:50:16.061 I/system_server(1294): Successfully killed process cgroup uid 10088 pid 2452 in 81ms
04-02 11:50:16.085 W/ActivityManager(1294): setHasOverlayUi called on unknown pid: 2452
04-02 11:50:16.558 W/OpenGLRenderer(2352): Incorrectly called buildLayer on View: ShortcutAndWidgetContainer, destroying layer...
04-02 11:50:16.576 I/zygote64(2352): Deoptimizing android.graphics.Bitmap com.android.launcher3.graphics.HolographicOutlineHelper.createMediumDropShadow(com.android.launcher3.BubbleTextView) due to JIT inline cache
04-02 11:50:18.063 I/zygote64(2352): Do full code cache collection, code=252KB, data=162KB
04-02 11:50:18.064 I/zygote64(2352): After code cache collection, code=246KB, data=140KB
04-02 11:50:18.662 W/ActivityManager(1294): Stopping service due to app idle: u0a98 -1m9s490ms org.telegram.messenger/.NotificationsService

[voidstarstar]

Do full code cache collection, code=250KB, data=160KB

Skipped 86 frames! The application may be doing too much work on its main thread.

The first and last logcat seem like the same issue. I wonder if it is crashing due to the garbage collector taking too long. If this is the case, it would probably be somewhat random and probably happen to other apps too. Does this device have low RAM? Does this crash only happen on that one device?

[Vavun]

Does this device have low RAM?

No, this device has 2Gb RAM and it allways about 500 ~ 200 free RAM.
I never faced a lack of memory on this device. (xiaomi redmi 4x 2/16) LineageOS 15.1

Does this crash only happen on that one device?

Yes.

You will laugh, but another device that has no problems, has only 768Mb (or something less 1Gb) available ram :)
My old HTC One S (LineageOS 14.1)

[Vavun]

Maybe this is important: fork interface freezes a little after tapping selected item and show it with little delay. Vanilla does not have this interface freezes.
But I can notice these freezing on any device, not only on the device with crashes.

It is almost not noticeable, but if you compare two screencasts with vanilla and fork it will be noticeable well.

[voidstarstar]

Yea, I can tell it freezes due to the Skipped 86 frames! The application may be doing too much work on its main thread. line, but I'm still not sure about the cause. It seems to indicate that this line might have something to do with it. (Maybe related to this?)

Does this only crash when you scroll through the list of apps? I also wonder if maybe it's something like a database lookup that takes too long.

[Vavun]

Maybe that is the reason ?
https://github.com/microg/android_external_MicroGUiTools/pull/13/files
SwitchBar

This is link to nanolx fork of MicroGUiTools https://github.com/Nanolx/android_external_MicroGUiTools/commits/master

[Vavun]

Does this only crash when you scroll through the list of apps?

You talking about freezes ? Or crash ?
Translation difficulties, sorrry

[voidstarstar]

I don't see any reason why the SwitchBar would cause this, but I could be wrong.

You talking about freezes ? Or crash ?

The crash.

[Vavun]

Does this only crash when you scroll through the list of apps?

No, I don't think so.

if I understand correctly, it was enough just to switch to GCM page after removing the app or try to unregiser ghost if it is were not enough.

[voidstarstar]

@Vavun I created #744 that fixes the bug I described above. I'm not sure if it fixes your bug, however. I was never able to see the ghost entry in the list. The app always disappears from the list for me. The only way I could get a ghost app to appear was if I had the app settings already open before uninstalling the app.

[voidstarstar]

@Vavun #744 should hopefully prevent the crash you described, but the ghost entry will probably still appear. I'm not sure why that entry wasn't removed when the app was uninstalled.

[voidstarstar]

Does this ghost entry only appear in Android 8.0+? If so, there's a good chance it's caused by a change in the API 26+ regarding implicit Intents. One easy way to see if this is happening is to look for GmsGcmUnregisterRcvr in the logcat when you uninstall an app. You will probably see this in the logcat on versions before 8.0 but not see it on versions 8.0 and newer.

See https://github.com/microg/android_packages_apps_GmsCore/pull/744#issuecomment-479196985

Unfortunately I don't have any 8.0+ devices, so if this is the issue, I can only try to debug or fix this blindly.

[Vavun]

Does this ghost entry only appear in Android 8.0+?

Yes, this problem appears only on my android 8.1. I didn'n faced ghosts on my test devices with android 6.0 and 7.1. (Unfortunately, I dn't have a device with android 9 to compare microg behavior in this sutuation , but I can install it if it need to be sure)
I have already read the discussion https://github.com/microg/android_packages_apps_GmsCore/pull/744

Thanks for your help again =)

Tell me If I can do something