gsantner / markor

Text editor - Notes & ToDo (for Android) - Markdown, todo.txt, plaintext, math, ..
https://github.com/gsantner/markor/discussions/2269
Other
3.87k stars 370 forks source link

onViewStateRestored: 2.10.3+ nightly build crashes on startup #1833

Closed Screwtapello closed 2 years ago

Screwtapello commented 2 years ago

⚠️ This issue respects the following points: ⚠️

Description

After the first-launch tour and the prompt for storage access permissions, and upon every launch thereafter, the "Mordor" nightly build crashes.

Steps to reproduce

  1. Download the latest nightly APK from the link in this issue template
  2. Extract the .zip, and install the APK to the phone with adb install
  3. Launch the "Mordor" app
  4. The first-launch tour appears; tap the "next" button repeatedly to get to the end
  5. A dialog appears, saying "Need permission for storage to save documents"
  6. Within a second or so, whether or not you try to tap OK or Cancel on the dialog, the app closes
  7. Try to launch the app again, it vanishes again, and after enough attempts you get the Android "Mordor keeps stopping/App info/Close app" dialog

Information

Android version: 10 Device: moto e7 power App Version: 2.10.3-2156

Source

Something else (-> Additional info)

Format / File type

Not specific

Additional info / Log

$ adb logcat -b crash
09-01 13:32:04.319 23628 23628 E AndroidRuntime: FATAL EXCEPTION: main
09-01 13:32:04.319 23628 23628 E AndroidRuntime: Process: net.gsantner.markor_test, PID: 23628
09-01 13:32:04.319 23628 23628 E AndroidRuntime: java.lang.NullPointerException: Attempt to invoke virtual method 'net.gsantner.markor.format.ActionButtonBase net.gsantner.markor.format.FormatRegistry.getTextActions()' on a null object reference
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at net.gsantner.markor.activity.DocumentEditAndViewFragment.setViewModeVisibility(DocumentEditAndViewFragment.java:755)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at net.gsantner.markor.activity.DocumentEditAndViewFragment.onViewStateRestored(DocumentEditAndViewFragment.java:230)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.fragment.app.Fragment.restoreViewState(Fragment.java:647)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.fragment.app.Fragment.restoreViewState(Fragment.java:3010)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.fragment.app.Fragment.performActivityCreated(Fragment.java:3001)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.fragment.app.FragmentStateManager.activityCreated(FragmentStateManager.java:580)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.fragment.app.FragmentStateManager.moveToExpectedState(FragmentStateManager.java:285)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.fragment.app.FragmentManager.executeOpsTogether(FragmentManager.java:2189)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.fragment.app.FragmentManager.removeRedundantOperationsAndExecute(FragmentManager.java:2100)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.fragment.app.FragmentManager.execSingleAction(FragmentManager.java:1971)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.fragment.app.BackStackRecord.commitNow(BackStackRecord.java:305)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.viewpager2.adapter.FragmentStateAdapter.placeFragmentInViewHolder(FragmentStateAdapter.java:341)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.viewpager2.adapter.FragmentStateAdapter.onViewAttachedToWindow(FragmentStateAdapter.java:276)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.viewpager2.adapter.FragmentStateAdapter.onViewAttachedToWindow(FragmentStateAdapter.java:67)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.RecyclerView.dispatchChildAttached(RecyclerView.java:7867)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.RecyclerView$5.addView(RecyclerView.java:893)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.ChildHelper.addView(ChildHelper.java:107)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.RecyclerView$LayoutManager.addViewInt(RecyclerView.java:8902)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.RecyclerView$LayoutManager.addView(RecyclerView.java:8860)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.RecyclerView$LayoutManager.addView(RecyclerView.java:8848)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.LinearLayoutManager.layoutChunk(LinearLayoutManager.java:1645)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.LinearLayoutManager.fill(LinearLayoutManager.java:1591)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.LinearLayoutManager.onLayoutChildren(LinearLayoutManager.java:668)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.RecyclerView.dispatchLayoutStep2(RecyclerView.java:4309)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.RecyclerView.dispatchLayout(RecyclerView.java:4012)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.recyclerview.widget.RecyclerView.onLayout(RecyclerView.java:4578)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.View.layout(View.java:22084)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.ViewGroup.layout(ViewGroup.java:6330)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at androidx.viewpager2.widget.ViewPager2.onLayout(ViewPager2.java:527)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.View.layout(View.java:22084)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.ViewGroup.layout(ViewGroup.java:6330)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.layoutChildren(FrameLayout.java:332)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.onLayout(FrameLayout.java:270)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.View.layout(View.java:22084)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.ViewGroup.layout(ViewGroup.java:6330)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.layoutChildren(FrameLayout.java:332)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.onLayout(FrameLayout.java:270)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.View.layout(View.java:22084)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.ViewGroup.layout(ViewGroup.java:6330)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.layoutChildren(FrameLayout.java:332)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.onLayout(FrameLayout.java:270)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.View.layout(View.java:22084)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.ViewGroup.layout(ViewGroup.java:6330)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.layoutChildren(FrameLayout.java:332)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.onLayout(FrameLayout.java:270)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.View.layout(View.java:22084)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.view.ViewGroup.layout(ViewGroup.java:6330)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.layoutChildren(FrameLayout.java:332)
09-01 13:32:04.319 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.onLayout(FrameLayout.java:270)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.View.layout(View.java:22084)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.ViewGroup.layout(ViewGroup.java:6330)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.layoutChildren(FrameLayout.java:332)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.widget.FrameLayout.onLayout(FrameLayout.java:270)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at com.android.internal.policy.DecorView.onLayout(DecorView.java:779)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.View.layout(View.java:22084)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.ViewGroup.layout(ViewGroup.java:6330)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.ViewRootImpl.performLayout(ViewRootImpl.java:3206)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.ViewRootImpl.performTraversals(ViewRootImpl.java:2702)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.ViewRootImpl.doTraversal(ViewRootImpl.java:1788)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.ViewRootImpl$TraversalRunnable.run(ViewRootImpl.java:7864)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.Choreographer$CallbackRecord.run(Choreographer.java:967)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.Choreographer.doCallbacks(Choreographer.java:791)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.Choreographer.doFrame(Choreographer.java:726)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.view.Choreographer$FrameDisplayEventReceiver.run(Choreographer.java:952)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.os.Handler.handleCallback(Handler.java:883)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.os.Handler.dispatchMessage(Handler.java:100)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.os.Looper.loop(Looper.java:214)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at android.app.ActivityThread.main(ActivityThread.java:7386)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at java.lang.reflect.Method.invoke(Native Method)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:492)
09-01 13:32:04.321 23628 23628 E AndroidRuntime:    at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:980)
gsantner commented 2 years ago

thanks, at #1831 there is a ongoing branch

harshad1 commented 2 years ago

You shouldn't have to use adb to install the nightly builds, right?

gsantner commented 2 years ago

Of course not but it's a possibility, however one likes to install. What however is far more helpful when a user knows adb -- is getting logs.

gsantner commented 2 years ago

I would really like to know why this happens for a few people. And not for us developers. I also have a real Android 10 device here and I use both the f-droid release version and the development version from git everday...and I never got this crash so far. And I build & install pretty much every single commit that is on master.

Screwtapello commented 2 years ago

I tried disabling dark mode, but that didn't help.

It doesn't look like it's been that long since 2.10.3, is there some archive of nightly builds we could use to bisect the changes since 2.10.3?

gsantner commented 2 years ago

The Actions tab (right to Markor Issues tab) should show all builds from the last 30 days.

Screwtapello commented 2 years ago

Looking just at CI actions on the Master branch:

It must be an issue with dea3ef95572dc3b8087950dd5775ec1dd8ee70af, but the diffstat is "11 changed files with 332 additions and 349 deletions". Given how little I know about Android or Java, I don't like my chances of narrowing the scope any further.

gsantner commented 2 years ago

Ok yes thanks for confirming that. It's also that commit which adds use of onViewStateRestored. Exactly what set to issue title as well and we can see in the stacktrace by you and the other user.

The problem is, that the onCreateView has not been called/fully called yet and many things are not initialized yet. Hence the NullPointer.

However what is always happening here, that the creation goes trough always firs ... as it supposed to work ... and then only reacb onViewStateRestored.

harshad1 commented 2 years ago

I've gone back to doing everything in onVewCreated in https://github.com/gsantner/markor/pull/1834.

Please try that build and see if this issue is resolved

gsantner commented 2 years ago

@Screwtapello can you please check with the latest git state of Markor master?

Screwtapello commented 2 years ago

I installed the build from CI 2175 and I'm sad to say it crashed again:

09-02 13:55:00.016 25376 25376 E AndroidRuntime: FATAL EXCEPTION: main
09-02 13:55:00.016 25376 25376 E AndroidRuntime: Process: net.gsantner.markor_test, PID: 25376
09-02 13:55:00.016 25376 25376 E AndroidRuntime: java.lang.NullPointerException: Attempt to invoke virtual method 'net.gsantner.markor.ui.hleditor.TextActions net.gsantner.markor.format.TextFormat.getTextActions()' on a null object reference
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at net.gsantner.markor.activity.DocumentEditFragment.setViewModeVisibility(DocumentEditFragment.java:760)
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at net.gsantner.markor.activity.DocumentEditFragment.onViewStateRestored(DocumentEditFragment.java:232)
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at androidx.fragment.app.Fragment.restoreViewState(Fragment.java:647)
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at androidx.fragment.app.Fragment.restoreViewState(Fragment.java:3010)
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at androidx.fragment.app.Fragment.performActivityCreated(Fragment.java:3001)
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at androidx.fragment.app.FragmentStateManager.activityCreated(FragmentStateManager.java:580)
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at androidx.fragment.app.FragmentStateManager.moveToExpectedState(FragmentStateManager.java:285)
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at androidx.fragment.app.FragmentManager.executeOpsTogether(FragmentManager.java:2189)
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at androidx.fragment.app.FragmentManager.removeRedundantOperationsAndExecute(FragmentManager.java:2100)
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at androidx.fragment.app.FragmentManager.execSingleAction(FragmentManager.java:1971)
09-02 13:55:00.016 25376 25376 E AndroidRuntime:    at androidx.fragment.app.BackStackRecord.commitNow(BackStackRecord.java:305)
(etc.)
gsantner commented 2 years ago

Can you please retry with latest master? There is no onViewStateRestored code anymore, so also nothing that would call a crashing setViewModeVisibility.

Screwtapello commented 2 years ago

Ah, sorry. Now that I look at the timestamp in the logs I pasted, they're from yesterday, so not related to today's build.

The latest CI run is CI 2179 built from commit ef03f0d. When I install the APK file with adb install, I can launch it and skip through the first-time tour. When I get to the point where previous builds crashed, the app still vanishes, but instead of the "Mordor keeps stopping" message, there's just a temporary message at the bottom of the screen, "Error: could not open." (the same kind of temporary message that announces things like "Uninstalled Mordor"). Sometimes I very briefly see a flicker of the "ask for filesystem permissions" dialog.

Nothing appears in the adb logcat -b crash buffer. adb logcat -b main has a lot of noise, and does not appear to contain anything like an actual error:

Very verbose logs...
09-03 14:00:51.216  1156  1453 I ActivityTaskManager: START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity bnds=[158,978][293,1152]} from uid 10087
09-03 14:00:51.221   592  1749 I BufferQueueProducer: [com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher#0](this:0xa8492000,id:7474,api:1,p:15739,c:592) queueBuffer: fps=2.02 dur=12868.39 max=12449.53 min=9.38
09-03 14:00:51.225   579   623 I power@1.3-impl: powerHintInternal hint:8, data:1
09-03 14:00:51.225   579   622 I libPowerHal: 8: cpu_ctrl set freq: 2001000 -1 1500000 -1
09-03 14:00:51.227  1156  1453 D PowerHalWrapper:  last:com.motorola.launcher3, next:net.gsantner.markor_test 
09-03 14:00:51.230   565   565 I hwcomposer: [HWCDisplay] [Display_0 (type:1)] fps:2.097689,dur:12871.31,max:12435.56,min:15.26  
09-03 14:00:51.235 14663 14663 W ActivityThread: handleWindowVisibility: no activity for token android.os.BinderProxy@d256478
09-03 14:00:51.237  1156  1453 D PowerHalWrapper: amsBoostNotify pid:14663,activity:net.gsantner.markor.activity.MainActivity, package:net.gsantner.markor_test, mProcessCreatePacknet.gsantner.markor_test 
09-03 14:00:51.239   579   622 I libPowerHal: [perfNotifyAppState] foreground:net.gsantner.markor_test, pid:14663, uid:10220
09-03 14:00:51.239   579   622 I UxUtility: notifyForegroundApp pack:net.gsantner.markor_test, uid:10220
09-03 14:00:51.239   579   622 I powerd  : [powerd_req] POWER_MSG_NOTIFY_STATE, pc:0, com.motorola.launcher3 => net.gsantner.markor_test
09-03 14:00:51.241  1156  1175 E system_server: Invalid ID 0x00000000.
09-03 14:00:51.245  1156  1175 D ViewRootImpl[markor_test]: hardware acceleration = false , fakeHwAccelerated = true, sRendererDisabled = false, forceHwAccelerated = false, sSystemRendererDisabled = false
09-03 14:00:51.249  1156  1175 I InputTransport: Create ARC handle: 0x80e27ed0
09-03 14:00:51.252 14663 15136 D libMEOW : applied 1 plugins for [net.gsantner.markor_test]:
09-03 14:00:51.252 14663 15136 D libMEOW :   plugin 1: [libMEOW_gift.so]:
09-03 14:00:51.258 29574 29574 D BoundBrokerSvc: onUnbind: Intent { act=com.google.android.gms.auth.cryptauth.cryptauthservice.START dat=chimera-action:com.google.android.gms.auth.cryptauth.cryptauthservice.START cmp=com.google.android.gms/.chimera.GmsBoundBrokerService }
09-03 14:00:51.262 14663 14663 I TwilightManager: Could not get last known location. This is probably because the app does not have any location permissions. Falling back to hardcoded sunrise/sunset values.
09-03 14:00:51.265   592  1749 I BufferQueue: [unnamed-592-7476](this:0xac2bb000,id:7476,api:0,p:-1,c:-1) BufferQueue core=(592:/system/bin/surfaceflinger)
09-03 14:00:51.265   592  1749 I BufferQueueConsumer: [unnamed-592-7476](this:0xac2bb000,id:7476,api:0,p:-1,c:592) connect(C): consumer=(592:/system/bin/surfaceflinger) controlledByApp=false
09-03 14:00:51.265   592  1749 I BufferQueueConsumer: [unnamed-592-7476](this:0xac2bb000,id:7476,api:0,p:-1,c:592) setConsumerName: unnamed-592-7476
09-03 14:00:51.265   592  1749 I BufferQueueConsumer: [Splash Screen net.gsantner.markor_test#0](this:0xac2bb000,id:7476,api:0,p:-1,c:592) setConsumerName: Splash Screen net.gsantner.markor_test#0
09-03 14:00:51.265   592  1749 I BufferQueueConsumer: [Splash Screen net.gsantner.markor_test#0](this:0xac2bb000,id:7476,api:0,p:-1,c:592) setDefaultBufferSize: width=720 height=1600
09-03 14:00:51.268  1156  1175 D Surface : lockCanvas
09-03 14:00:51.268  1156  1175 D Surface : Surface::connect(this=0x80902000,api=2)
09-03 14:00:51.269   592   725 I BufferQueueProducer: [Splash Screen net.gsantner.markor_test#0](this:0xac2bb000,id:7476,api:2,p:1156,c:592) connect(P): api=2 producer=(1156:system_server) producerControlledByApp=false
09-03 14:00:51.295 14663 14663 I TwilightManager: Could not get last known location. This is probably because the app does not have any location permissions. Falling back to hardcoded sunrise/sunset values.
09-03 14:00:51.296 15117 15117 I MultiDex: VM with version 2.1.0 has multidex support
09-03 14:00:51.296 15117 15117 I MultiDex: Installing application
09-03 14:00:51.296 15117 15117 I MultiDex: VM has multidex support, MultiDex support library is disabled.
09-03 14:00:51.398  1156  2422 V SettingsProvider: packageValueForCallResult, name = sqlite_compatibility_wal_flags, value : Bundle[{_track_generation=android.util.MemoryIntArray@78, value=, _generation_index=0, _generation=616}]
09-03 14:00:51.400   592   592 I BufferQueueProducer: [FramebufferSurface](this:0xae0a3000,id:0,api:1,p:592,c:592) queueBuffer: fps=0.02 dur=143048.59 max=143019.08 min=13.95
09-03 14:00:51.482 14663 14663 I TwilightManager: Could not get last known location. This is probably because the app does not have any location permissions. Falling back to hardcoded sunrise/sunset values.
09-03 14:00:51.499 14663 14663 I chatty  : uid=10220(net.gsantner.markor_test) identical 1 line
09-03 14:00:51.503 14663 14663 I TwilightManager: Could not get last known location. This is probably because the app does not have any location permissions. Falling back to hardcoded sunrise/sunset values.
09-03 14:00:51.535  1304  1656 D NetworkController.MobileSignalController(1)(0): onSignalStrengthsChanged signalStrength=SignalStrength:{mCdma=CellSignalStrengthCdma: cdmaDbm=2147483647 cdmaEcio=2147483647 evdoDbm=2147483647 evdoEcio=2147483647 evdoSnr=2147483647 level=0,mGsm=CellSignalStrengthGsm: rssi=2147483647 ber=2147483647 mTa=2147483647 mLevel=0,mWcdma=CellSignalStrengthWcdma: ss=2147483647 ber=2147483647 rscp=2147483647 ecno=2147483647 level=0,mTdscdma=CellSignalStrengthTdscdma: rssi=2147483647 ber=2147483647 rscp=2147483647 level=0,mLte=CellSignalStrengthLte: rssi=2147483647 rsrp=-104 rsrq=-15 rssnr=0 cqi=0 ta=0 level=1,mNr=CellSignalStrengthNr:{ csiRsrp = 2147483647 csiRsrq = 2147483647 csiSinr = 2147483647 ssRsrp = 2147483647 ssRsrq = 2147483647 ssSinr = 2147483647 level = 0 },primary=CellSignalStrengthLte}, phoneId=0, mMtkLevel=-1, mMtkRsrpOnly=true, mMtkLteRsrpThreshold=null, mMtkLteRssnrThreshold=null level=1
09-03 14:00:51.539  1304  1656 D NetworkController.MobileSignalController(1)(0): updateTelephony(), level_tmp = 3, mCurrentState.level = 1
09-03 14:00:51.539  1304  1656 D NetworkController.MobileSignalController(1)(0): mapIconsByMccncc
09-03 14:00:51.540   592   725 I BufferQueueProducer: [StatusBar#0](this:0xac222000,id:5,api:1,p:1304,c:592) queueBuffer: fps=0.70 dur=12914.22 max=12789.41 min=12.06
09-03 14:00:51.541  1304  1656 D NetworkController.MobileSignalController(1)(0): matchCards mccMnc: 50502
09-03 14:00:51.544  1304  1656 D NetworkController.MobileSignalController(1)(0): matchCards mccMnc: 50502
09-03 14:00:51.544   592   725 I BufferQueueProducer: [NavigationBar0#0](this:0xa95b2000,id:7475,api:1,p:1304,c:592) queueBuffer: fps=0.70 dur=12911.97 max=12789.74 min=11.53
09-03 14:00:51.549  1304  1656 D NetworkController.MobileSignalController(1)(0): matchSimMcc mccMnc: 50502, mcc = 260
09-03 14:00:51.550  1304  1656 D NetworkController.MobileSignalController(1)(0): matchCards mccMnc: 50502
09-03 14:00:51.552  1304  1656 D NetworkController.MobileSignalController(1)(0): matchCards isNullMccNcc: 50502
09-03 14:00:51.554  1304  1656 D NetworkController.MobileSignalController(1)(0): isDataDisabled = true
09-03 14:00:51.557  1304  1656 D NetworkTypeUtils: nrState = -1
09-03 14:00:51.572 14663 14663 D ViewRootImpl[MainActivity]: hardware acceleration = true , fakeHwAccelerated = false, sRendererDisabled = false, forceHwAccelerated = false, sSystemRendererDisabled = false
09-03 14:00:51.575  1156  1484 E WindowManager: App trying to use insecure INPUT_FEATURE_NO_INPUT_CHANNEL flag. Ignoring
09-03 14:00:51.577  1156  1484 I WindowManager: ***** PhoneWindowManager, focusChangedLw, newFocus=Token{3a65b38 ActivityRecord{e4e0b9b u0 net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity t5806}}
09-03 14:00:51.582 14663 14663 I InputTransport: Create ARC handle: 0x8a9097a0
09-03 14:00:51.582 14663 15146 D libMEOW_gift: etEventThreadFunc()::thread Begin. eventRefCnt: 1
09-03 14:00:51.585 14663 14663 V PhoneWindow: DecorView setVisiblity: visibility = 4, Parent = null, this = DecorView@df14b09[]
09-03 14:00:51.590 14663 14663 D ViewRootImpl[MainActivity]: hardware acceleration = true , fakeHwAccelerated = false, sRendererDisabled = false, forceHwAccelerated = false, sSystemRendererDisabled = false
09-03 14:00:51.592  1156  2422 E WindowManager: App trying to use insecure INPUT_FEATURE_NO_INPUT_CHANNEL flag. Ignoring
09-03 14:00:51.596 14663 14663 I InputTransport: Create ARC handle: 0x8a9097f0
09-03 14:00:51.597 14663 14663 V PhoneWindow: DecorView setVisiblity: visibility = 0, Parent = android.view.ViewRootImpl@91dd13c, this = DecorView@df14b09[MainActivity]
09-03 14:00:51.586  1304  1304 W SysUiBg : type=1400 audit(0.0:170857): avc: denied { read } for name="u:object_r:mtk_telephony_sensitive_prop:s0" dev="tmpfs" ino=9200 scontext=u:r:platform_app:s0:c512,c768 tcontext=u:object_r:mtk_telephony_sensitive_prop:s0 tclass=file permissive=0
09-03 14:00:51.599  1304  1656 E libc    : Access denied finding property "vendor.ril.iccid.sim1"
09-03 14:00:51.599  1304  1656 D NetworkController.MobileSignalController(1)(0): ims = -1, mDisableWfc = false, imsRegState = 1, volteSwitch = true
09-03 14:00:51.619   592  1748 I BufferQueue: [unnamed-592-7477](this:0xa95bb000,id:7477,api:0,p:-1,c:-1) BufferQueue core=(592:/system/bin/surfaceflinger)
09-03 14:00:51.620   592  1748 I BufferQueueConsumer: [unnamed-592-7477](this:0xa95bb000,id:7477,api:0,p:-1,c:592) connect(C): consumer=(592:/system/bin/surfaceflinger) controlledByApp=false
09-03 14:00:51.620   592  1748 I BufferQueueConsumer: [unnamed-592-7477](this:0xa95bb000,id:7477,api:0,p:-1,c:592) setConsumerName: unnamed-592-7477
09-03 14:00:51.620   592  1748 I BufferQueueConsumer: [net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#0](this:0xa95bb000,id:7477,api:0,p:-1,c:592) setConsumerName: net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#0
09-03 14:00:51.620   592  1748 I BufferQueueConsumer: [net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#0](this:0xa95bb000,id:7477,api:0,p:-1,c:592) setDefaultBufferSize: width=795 height=380
09-03 14:00:51.626 14663 14760 D Surface : Surface::connect(this=0x8bcc3800,api=1)
09-03 14:00:51.627   592  1749 I BufferQueueProducer: [net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#0](this:0xa95bb000,id:7477,api:1,p:14663,c:592) connect(P): api=1 producer=(14663:net.gsantner.markor_test) producerControlledByApp=false
09-03 14:00:51.628 14663 14760 D Surface : Surface::setBufferCount(this=0x8bcc3800,bufferCount=3)
09-03 14:00:51.629 14663 14760 D Surface : Surface::allocateBuffers(this=0x8bcc3800)
09-03 14:00:51.683  1156  2422 I WindowManager: Relayout Window{8fbfb0 u0 net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity}: oldVis=4 newVis=0 focusMayChange = true
09-03 14:00:51.686   592  1749 I BufferQueue: [unnamed-592-7478](this:0xac2c3000,id:7478,api:0,p:-1,c:-1) BufferQueue core=(592:/system/bin/surfaceflinger)
09-03 14:00:51.687   592  1749 I BufferQueueConsumer: [unnamed-592-7478](this:0xac2c3000,id:7478,api:0,p:-1,c:592) connect(C): consumer=(592:/system/bin/surfaceflinger) controlledByApp=false
09-03 14:00:51.688   592  1749 I BufferQueueConsumer: [unnamed-592-7478](this:0xac2c3000,id:7478,api:0,p:-1,c:592) setConsumerName: unnamed-592-7478
09-03 14:00:51.688   592  1749 I BufferQueueConsumer: [net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#1](this:0xac2c3000,id:7478,api:0,p:-1,c:592) setConsumerName: net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#1
09-03 14:00:51.688   592  1749 I BufferQueueConsumer: [net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#1](this:0xac2c3000,id:7478,api:0,p:-1,c:592) setDefaultBufferSize: width=720 height=1600
09-03 14:00:51.694 14663 14760 D Surface : Surface::connect(this=0xa06ae800,api=1)
09-03 14:00:51.694   592   725 I BufferQueueProducer: [net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#1](this:0xac2c3000,id:7478,api:1,p:14663,c:592) connect(P): api=1 producer=(14663:net.gsantner.markor_test) producerControlledByApp=false
09-03 14:00:51.695 14663 14760 D Surface : Surface::setBufferCount(this=0xa06ae800,bufferCount=3)
09-03 14:00:51.695 14663 14760 D Surface : Surface::allocateBuffers(this=0xa06ae800)
09-03 14:00:51.797   592   592 E Layer   : [Surface(name=AppWindowToken{2f01711 token=Token{3a65b38 ActivityRecord{e4e0b9b u0 net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity t5806}}})/@0xd052b5a - animation-leash#0] No local sync point found
09-03 14:00:51.797   592   592 E Layer   : [Surface(name=AppWindowToken{2f01711 token=Token{3a65b38 ActivityRecord{e4e0b9b u0 net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity t5806}}})/@0xd052b5a - animation-leash#0] No local sync point found
09-03 14:00:51.797   592   592 E Layer   : [Surface(name=AppWindowToken{b0a116d token=Token{3349584 ActivityRecord{9dacf97 u0 com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher t5130}}})/@0x3941a1a - animation-leash#0] No local sync point found
09-03 14:00:51.797   592   592 E Layer   : [Surface(name=AppWindowToken{b0a116d token=Token{3349584 ActivityRecord{9dacf97 u0 com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher t5130}}})/@0x3941a1a - animation-leash#0] No local sync point found
09-03 14:00:51.809 15739 15767 D Surface : Surface::disconnect(this=0x8b42c000,api=1)
09-03 14:00:51.810   592  1748 I BufferQueueProducer: [com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher#0](this:0xa8492000,id:7474,api:1,p:15739,c:592) disconnect(P): api 1
09-03 14:00:51.842  1156  3041 D PowerHalWrapper:  last:net.gsantner.markor_test, next:com.motorola.launcher3 
09-03 14:00:51.943  1156  1484 W ActivityTaskManager: Finishing task with all activities already finished
09-03 14:00:51.943  1156  1484 W ActivityTaskManager: Duplicate finish request for ActivityRecord{e4e0b9b u0 net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity t5806 f}
09-03 14:00:52.165 14663 14663 D View    : [ANR Warning]onLayout time too long, this =androidx.viewpager2.widget.ViewPager2$RecyclerViewImpl{7d915f2 VFED..... ......ID 0,0-720,1273 #1b}time =464 ms
09-03 14:00:52.165 14663 14663 D View    : [ANR Warning]onLayout time too long, this =androidx.viewpager2.widget.ViewPager2{74a4443 V.E...... ......ID 0,98-720,1371 #7f09016c app:id/main__view_pager_container}time =464 ms
09-03 14:00:52.169 14663 14663 D View    : [ANR Warning]onLayout time too long, this =android.widget.FrameLayout{a3daac0 V.E...... ......ID 0,0-720,1469 #7f09016d app:id/main_content}time =469 ms
09-03 14:00:52.169 14663 14663 D View    : [ANR Warning]onLayout time too long, this =androidx.appcompat.widget.ContentFrameLayout{6c863f9 V.E...... ......ID 0,0-720,1469 #1020002 android:id/content}time =470 ms
09-03 14:00:52.169 14663 14663 D View    : [ANR Warning]onLayout time too long, this =androidx.appcompat.widget.FitWindowsFrameLayout{d5e9f3e V.E...... ......ID 0,0-720,1469 #7f09003f app:id/action_bar_root}time =470 ms
09-03 14:00:52.169 14663 14663 D View    : [ANR Warning]onLayout time too long, this =android.widget.FrameLayout{dc2e19f V.E...... ......ID 0,47-720,1516}time =470 ms
09-03 14:00:52.169 14663 14663 D View    : [ANR Warning]onLayout time too long, this =android.widget.FrameLayout{cb2b2ec V.E...... ......ID 0,0-720,1516}time =470 ms
09-03 14:00:52.170 14663 14663 D View    : [ANR Warning]onLayout time too long, this =DecorView@df14b09[MainActivity]time =471 ms
09-03 14:00:52.170 14663 14663 W View    : requestLayout() improperly called by androidx.appcompat.widget.AppCompatTextView{92246b5 V.ED..... ......ID 28,25-143,72} during layout: running second layout pass
09-03 14:00:52.220 14663 14663 I Choreographer: Skipped 36 frames!  The application may be doing too much work on its main thread.
09-03 14:00:52.234   565   565 I hwcomposer: [HWCDisplay] [Display_0 (type:1)] fps:59.730709,dur:1004.51,max:20.67,min:15.21  
09-03 14:00:52.280  1156  1175 D View    : [Warning] assignParent to null: this = DecorView@4944237[markor_test]
09-03 14:00:52.282  1156  1175 D Surface : Surface::disconnect(this=0x80902000,api=2)
09-03 14:00:52.282   592   725 I BufferQueueProducer: [Splash Screen net.gsantner.markor_test#0](this:0xac2bb000,id:7476,api:2,p:1156,c:592) disconnect(P): api 2
09-03 14:00:52.283  1156  1175 I InputTransport: Destroy ARC handle: 0x80e27ed0
09-03 14:00:52.289  1156  4318 I GED     : ged_boost_gpu_freq, level 100, eOrigin 2, final_idx 2, oppidx_max 2, oppidx_min 0
09-03 14:00:52.292  1156  1164 I system_server: Background concurrent copying GC freed 17607(1044KB) AllocSpace objects, 2(4712KB) LOS objects, 8% free, 62MB/68MB, paused 365us total 926.768ms
09-03 14:00:52.319 14663 14760 I OpenGLRenderer: Davey! duration=703ms; Flags=0, IntendedVsync=738485089028567, Vsync=738485689028579, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=738485696502854, AnimationStart=738485696596470, PerformTraversalsStart=738485790191931, DrawStart=738485790540393, SyncQueued=738485790864623, SyncStart=738485793554239, IssueDrawCommandsStart=738485793639700, SwapBuffers=738485794335085, FrameCompleted=738485795460931, DequeueBufferDuration=0, QueueBufferDuration=615000, 
09-03 14:00:52.340  1156  1174 W ActivityTaskManager: Activity top resumed state loss timeout for ActivityRecord{e4e0b9b u0 net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity t5806 f}
09-03 14:00:52.342  1156  1174 W ActivityTaskManager: Activity pause timeout for ActivityRecord{e4e0b9b u0 net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity t5806 f}
09-03 14:00:52.344  1156  1174 D PowerHalWrapper: amsBoostNotify pid:15739,activity:com.android.launcher3.CustomizationPanelLauncher, package:com.motorola.launcher3, mProcessCreatePacknet.gsantner.markor_test 
09-03 14:00:52.344  1156  1174 D PowerHalWrapper: amsBoostNotify AMS_BOOST_PROCESS_CREATE_BOOST:false 
09-03 14:00:52.345   579   622 I libPowerHal: [perfNotifyAppState] foreground:com.motorola.launcher3, pid:15739, uid:10087
09-03 14:00:52.345   579   622 I UxUtility: notifyForegroundApp pack:com.motorola.launcher3, uid:10087
09-03 14:00:52.345   579   622 I powerd  : [powerd_req] POWER_MSG_NOTIFY_STATE, pc:0, net.gsantner.markor_test => com.motorola.launcher3
09-03 14:00:52.353  1156  2422 I WindowManager: Relayout Window{919f605 u0 com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher}: oldVis=8 newVis=0 focusMayChange = true
09-03 14:00:52.356  1156  2422 I WindowManager: ***** PhoneWindowManager, focusChangedLw, newFocus=Token{3349584 ActivityRecord{9dacf97 u0 com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher t5130}}
09-03 14:00:52.359 14663 14760 I OpenGLRenderer: Davey! duration=743ms; Flags=0, IntendedVsync=738485089028567, Vsync=738485689028579, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=738485696502854, AnimationStart=738485696596470, PerformTraversalsStart=738485790191931, DrawStart=738485803137085, SyncQueued=738485816800931, SyncStart=738485820294393, IssueDrawCommandsStart=738485820697162, SwapBuffers=738485831417316, FrameCompleted=738485835781393, DequeueBufferDuration=0, QueueBufferDuration=3322000, 
09-03 14:00:52.360 15739 15767 D Surface : Surface::connect(this=0x8b42c000,api=1)
09-03 14:00:52.360   592  1749 I BufferQueueProducer: [com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher#0](this:0xa8492000,id:7474,api:1,p:15739,c:592) connect(P): api=1 producer=(15739:com.motorola.launcher3) producerControlledByApp=false
09-03 14:00:52.361 14663 14663 D ViewRootImpl[Toast]: hardware acceleration = true , fakeHwAccelerated = false, sRendererDisabled = false, forceHwAccelerated = false, sSystemRendererDisabled = false
09-03 14:00:52.361 15739 15767 D Surface : Surface::setBufferCount(this=0x8b42c000,bufferCount=3)
09-03 14:00:52.361 15739 15767 D Surface : Surface::allocateBuffers(this=0x8b42c000)
09-03 14:00:52.362  1156  3041 E WindowManager: App trying to use insecure INPUT_FEATURE_NO_INPUT_CHANNEL flag. Ignoring
09-03 14:00:52.367 14663 14663 I InputTransport: Create ARC handle: 0x85a5c250
09-03 14:00:52.376   592   724 I BufferQueueProducer: [com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher#0](this:0xa8492000,id:7474,api:1,p:15739,c:592) queueBuffer: fps=30.30 dur=1155.10 max=576.50 min=4.24
09-03 14:00:52.380 15739 15739 V PhoneWindow: DecorView setVisiblity: visibility = 0, Parent = android.view.ViewRootImpl@1231fe0, this = DecorView@70b8047[CustomizationPanelLauncher]
09-03 14:00:52.388   592   592 I SurfaceFlinger: screenshot (net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#1)
09-03 14:00:52.388   592   592 I SurfaceFlinger: screenshot (Dim Layer for - Task=5806#0)
09-03 14:00:52.388   592   592 I SurfaceFlinger: screenshot (net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#0)
09-03 14:00:52.388   592   592 I SurfaceFlinger: screenshot (Splash Screen net.gsantner.markor_test#0)
09-03 14:00:52.435   579   623 I power@1.3-impl: powerHintInternal hint:8, data:0
09-03 14:00:52.436  1156  2453 D PowerHalWrapper: amsBoostStop AMS_BOOST_PACK_SWITCH:true, AMS_BOOST_ACT_SWITCH:false, AMS_BOOST_PROCESS_CREATE:false 
09-03 14:00:52.438   579   622 I libPowerHal: 32: cpu_ctrl set freq: -1 -1 -1 -1
09-03 14:00:52.442   693   791 D AAL     : 09-03 02:00:51.521 BL= 181,ESS= 256, 09-03 02:00:51.621 BL= 182,ESS= 256, 09-03 02:00:51.772 BL= 183,ESS= 256, 09-03 02:00:52.191 BL= 184,ESS= 256, 09-03 02:00:52.442 BL= 183,ESS= 256, 
09-03 14:00:52.457   592   592 I BufferQueueProducer: [FramebufferSurface](this:0xae0a3000,id:0,api:1,p:592,c:592) queueBuffer: fps=22.70 dur=1057.22 max=672.92 min=15.53
09-03 14:00:52.469   592   592 I BufferQueue: [Splash Screen net.gsantner.markor_test#0](this:0xac2bb000,id:7476,api:2,p:-1,c:-1) ~BufferQueueCore
09-03 14:00:52.501   592  1749 I BufferQueue: [unnamed-592-7479](this:0xac2ce000,id:7479,api:0,p:-1,c:-1) BufferQueue core=(592:/system/bin/surfaceflinger)
09-03 14:00:52.501   592  1749 I BufferQueueConsumer: [unnamed-592-7479](this:0xac2ce000,id:7479,api:0,p:-1,c:592) connect(C): consumer=(592:/system/bin/surfaceflinger) controlledByApp=false
09-03 14:00:52.501   592  1749 I BufferQueueConsumer: [unnamed-592-7479](this:0xac2ce000,id:7479,api:0,p:-1,c:592) setConsumerName: unnamed-592-7479
09-03 14:00:52.501   592  1749 I BufferQueueConsumer: [Toast#0](this:0xac2ce000,id:7479,api:0,p:-1,c:592) setConsumerName: Toast#0
09-03 14:00:52.501   592  1749 I BufferQueueConsumer: [Toast#0](this:0xac2ce000,id:7479,api:0,p:-1,c:592) setDefaultBufferSize: width=322 height=86
09-03 14:00:52.512 14663 14760 D Surface : Surface::connect(this=0x8bcc3000,api=1)
09-03 14:00:52.512   592   724 I BufferQueueProducer: [Toast#0](this:0xac2ce000,id:7479,api:1,p:14663,c:592) connect(P): api=1 producer=(14663:net.gsantner.markor_test) producerControlledByApp=false
09-03 14:00:52.513 14663 14760 D Surface : Surface::setBufferCount(this=0x8bcc3000,bufferCount=3)
09-03 14:00:52.514 14663 14760 D Surface : Surface::allocateBuffers(this=0x8bcc3000)
09-03 14:00:52.546   592   725 I BufferQueueProducer: [StatusBar#0](this:0xac222000,id:5,api:1,p:1304,c:592) queueBuffer: fps=16.89 dur=1006.79 max=735.99 min=14.57
09-03 14:00:52.551   592  1749 I BufferQueueProducer: [NavigationBar0#0](this:0xa95b2000,id:7475,api:1,p:1304,c:592) queueBuffer: fps=52.65 dur=1006.66 max=126.44 min=11.98
09-03 14:00:52.578  1304  1316 I ndroid.systemu: NativeAlloc concurrent copying GC freed 107944(5342KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 9517KB/15MB, paused 141us total 166.940ms
09-03 14:00:52.596  4954  4954 W tteryBotPro.BIS: type=1400 audit(0.0:170858): avc: denied { search } for name="battery" dev="sysfs" ino=7387 scontext=u:r:untrusted_app:s0:c201,c256,c512,c768 tcontext=u:object_r:sysfs_batteryinfo:s0 tclass=dir permissive=0
09-03 14:00:52.628   693   791 D AAL     : 09-03 02:00:52.475 BL= 182,ESS= 256, 09-03 02:00:52.510 BL= 185,ESS= 256, 09-03 02:00:52.525 BL= 187,ESS= 256, 09-03 02:00:52.576 BL= 186,ESS= 256, 09-03 02:00:52.628 BL= 189,ESS= 256, 
09-03 14:00:52.887 14663 14760 D Surface : Surface::disconnect(this=0x8bcc3800,api=1)
09-03 14:00:52.889   592  1749 I BufferQueueProducer: [net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#0](this:0xa95bb000,id:7477,api:1,p:14663,c:592) disconnect(P): api 1
09-03 14:00:52.907 14663 14760 D Surface : Surface::disconnect(this=0xa06ae800,api=1)
09-03 14:00:52.908   592  1748 I BufferQueueProducer: [net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#1](this:0xac2c3000,id:7478,api:1,p:14663,c:592) disconnect(P): api 1
09-03 14:00:52.919 14663 14760 I GED     : ged_boost_gpu_freq, level 100, eOrigin 2, final_idx 2, oppidx_max 2, oppidx_min 0
09-03 14:00:52.945 14663 14663 V PhoneWindow: DecorView setVisiblity: visibility = 4, Parent = android.view.ViewRootImpl@91dd13c, this = DecorView@df14b09[MainActivity]
09-03 14:00:52.983 14663 14663 W cr_AwContents: WebView.destroy() called while WebView is still attached to window.
09-03 14:00:52.995   693   791 D AAL     : 09-03 02:00:52.710 BL= 188,ESS= 256, 09-03 02:00:52.779 BL= 187,ESS= 256, 09-03 02:00:52.844 BL= 186,ESS= 256, 09-03 02:00:52.927 BL= 185,ESS= 256, 09-03 02:00:52.994 BL= 184,ESS= 256, 
09-03 14:00:53.004 14663 14663 W cr_AwContents: WebView.destroy() called while WebView is still attached to window.
09-03 14:00:53.015 17159 17159 D Zygote  : Forked child process 15151
09-03 14:00:53.022  1156  1181 I ActivityManager: Start proc 15151:com.google.android.webview:sandboxed_process0:org.chromium.content.app.SandboxedProcessService0:5/u0i148 for  {net.gsantner.markor_test/org.chromium.content.app.SandboxedProcessService0:5}
09-03 14:00:53.039 14663 14663 D View    : [Warning] assignParent to null: this = DecorView@df14b09[MainActivity]
09-03 14:00:53.039 14663 14663 I InputTransport: Destroy ARC handle: 0x8a9097f0
09-03 14:00:53.045 14663 14663 E WindowManager: 
09-03 14:00:53.045 14663 14663 E WindowManager: android.view.WindowLeaked: Activity net.gsantner.markor.activity.MainActivity has leaked window DecorView@272d586[MainActivity] that was originally added here
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.view.ViewRootImpl.(ViewRootImpl.java:614)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.view.WindowManagerGlobal.addView(WindowManagerGlobal.java:377)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.view.WindowManagerImpl.addView(WindowManagerImpl.java:95)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.app.Dialog.show(Dialog.java:342)
09-03 14:00:53.045 14663 14663 E WindowManager:     at androidx.appcompat.app.AlertDialog$Builder.show(AlertDialog.java:1009)
09-03 14:00:53.045 14663 14663 E WindowManager:     at net.gsantner.opoc.util.GsContextUtils.checkExternalStoragePermission(GsContextUtils.java:2393)
09-03 14:00:53.045 14663 14663 E WindowManager:     at net.gsantner.opoc.frontend.settings.GsPermissionChecker.doIfExtStoragePermissionGranted(GsPermissionChecker.java:30)
09-03 14:00:53.045 14663 14663 E WindowManager:     at net.gsantner.markor.frontend.settings.MarkorPermissionChecker.doIfExtStoragePermissionGranted(MarkorPermissionChecker.java:27)
09-03 14:00:53.045 14663 14663 E WindowManager:     at net.gsantner.markor.activity.MainActivity.onResume(MainActivity.java:207)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.app.Instrumentation.callActivityOnResume(Instrumentation.java:1453)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.app.Activity.performResume(Activity.java:7939)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.app.ActivityThread.performResumeActivity(ActivityThread.java:4223)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.app.ActivityThread.handleResumeActivity(ActivityThread.java:4265)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.app.servertransaction.ResumeActivityItem.execute(ResumeActivityItem.java:52)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.app.servertransaction.TransactionExecutor.executeLifecycleState(TransactionExecutor.java:176)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.app.servertransaction.TransactionExecutor.execute(TransactionExecutor.java:97)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2041)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.os.Handler.dispatchMessage(Handler.java:107)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.os.Looper.loop(Looper.java:214)
09-03 14:00:53.045 14663 14663 E WindowManager:     at android.app.ActivityThread.main(ActivityThread.java:7386)
09-03 14:00:53.045 14663 14663 E WindowManager:     at java.lang.reflect.Method.invoke(Native Method)
09-03 14:00:53.045 14663 14663 E WindowManager:     at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:492)
09-03 14:00:53.045 14663 14663 E WindowManager:     at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:980)
09-03 14:00:53.046 14663 14663 D View    : [Warning] assignParent to null: this = DecorView@272d586[MainActivity]
09-03 14:00:53.053   592   592 I BufferQueue: [net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#1](this:0xac2c3000,id:7478,api:1,p:-1,c:-1) ~BufferQueueCore
09-03 14:00:53.054   592   592 I BufferQueue: [net.gsantner.markor_test/net.gsantner.markor.activity.MainActivity#0](this:0xa95bb000,id:7477,api:1,p:-1,c:-1) ~BufferQueueCore
09-03 14:00:53.055 15151 15151 E ocessService0:: Not starting debugger since process cannot load the jdwp agent.
09-03 14:00:53.062 14663 14663 I InputTransport: Destroy ARC handle: 0x8a9097a0
09-03 14:00:53.065  1156  2422 W WindowManager: Failed looking up window callers=com.android.server.wm.WindowManagerService.windowForClientLocked:5353 com.android.server.wm.WindowManagerService.removeWindow:1796 com.android.server.wm.Session.remove:176 
09-03 14:00:53.075  1156  2422 V ActivityManager: New death recipient com.android.server.am.ActivityManagerService$AppDeathRecipient@7135da5 for thread android.os.BinderProxy@c65557a, pid:15151,mApp:null
09-03 14:00:53.078  1156  4318 I libMEOW_gift: ctx:0x87b935e0, ARC not Enabled.
09-03 14:00:53.101  1156  4318 I GED     : ged_boost_gpu_freq, level 100, eOrigin 2, final_idx 2, oppidx_max 2, oppidx_min 0
09-03 14:00:53.102 15151 15151 I cr_WebViewApkApp: Launched version=104.0.5112.97 minSdkVersion=29 isBundle=true processName=com.google.android.webview:sandboxed_process0:org.chromium.content.app.SandboxedProcessService0:5
09-03 14:00:53.107 15151 15151 I cr_ChildProcessService: Creating new ChildProcessService pid=15151
09-03 14:00:53.123  1156  1453 I ActivityManager: Killing 15151:com.google.android.webview:sandboxed_process0:org.chromium.content.app.SandboxedProcessService0:5/u0a220i148 (adj 0): isolated not needed
09-03 14:00:53.124  1156  1182 W libprocessgroup: Failed to open process cgroup uid 99148 pid 15151: Permission denied
09-03 14:00:53.125  1156  1182 E libprocessgroup: Error encountered killing process cgroup uid 99148 pid 15151: Permission denied
09-03 14:00:53.148  1156  2422 V ActivityManager: Death received in com.android.server.am.ActivityManagerService$AppDeathRecipient@7135da5 for thread android.os.BinderProxy@c65557a, mPid:15151,mApp:ProcessRecord{2012f21 15151:com.google.android.webview:sandboxed_process0:org.chromium.content.app.SandboxedProcessService0:5/u0a220i148}
09-03 14:00:53.149 17159 17159 I Zygote  : Process 15151 exited due to signal 9 (Killed)
09-03 14:00:53.951   565   565 I hwcomposer: [HWC] (0) displayGetActiveConfig config:0  
09-03 14:00:53.968   592  1748 I BufferQueueProducer: [com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher#0](this:0xa8492000,id:7474,api:1,p:15739,c:592) queueBuffer: fps=18.21 dur=1592.32 max=1087.98 min=11.51
09-03 14:00:53.975   565   565 I hwcomposer: [HWCDisplay] [Display_0 (type:1)] fps:29.871286,dur:1740.80,max:887.86,min:10.66  
Screwtapello commented 2 years ago

Digging through CI runs from PR #1834, it looks like CI 2167 is the last build that crashes "properly", 2168-70 are failed builds, and CI 2171 is the first to crash in this weird way.

hydrargyrum commented 2 years ago

@gsantner here's what I have with the https://github.com/gsantner/markor/actions/runs/2982641902 build:

first run after uninstall/reinstall:

subsequents run:

Suddenly, I had an idea I didn't have before: I give storage permissions manually by navigating android menus, now mordor does not crash anymore! So it seems current problem on my phone is mordor does not ask permissions in a way where it waits for the answer but quits too quickly.

However, mordor is much slower than markor:

Screwtapello commented 2 years ago

Can confirm. If I install the CI 2179 build, then manually give it "Storage" permissions (long press → App Info → Permissions → Storage → Allow) before starting it the first time, it runs perfectly.

Screwtapello commented 2 years ago

I can also confirm that Mordor waits on a grey screen for about five seconds before the file list appears.

gsantner commented 2 years ago

@Screwtapello @hydrargyrum
Here is a build with reduced amount of permission checks. Seems like those are relevant for your delays if I'm right >>> https://github.com/gsantner/markor/actions/runs/2983883226

Screwtapello commented 2 years ago

Testing:

As mentioned above, the previous build took ~5 seconds to be ready, this build takes ~3.5 seconds. There is an improvement, but it's not as quick as the current stable build.