Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[UnifiedMap] [nightly] crash on panning / zooming / loading caches on map #15726

Open
murggel opened this issue May 12, 2024 · 46 comments
Open
Labels
Bug Issues classified as a bug Prio - High A significant malfunction of a feature/function. High user impact. Unverified Issue not yet confirmed/reproduced or feature requests not yet checked for plausibility

Comments

@murggel
Copy link
Contributor

murggel commented May 12, 2024

Describe your problem!

On panning / zooming the map (UnifiedMap), the map crashes.
Sometimes it needs 2 or 3 steps - probably related to the visible cash count?

even crashes on just opening the map...

does not crash, if only a few cashes are visible

How to reproduce?

Open UnifiedMap around Stuttgart
Pan / zoom out

Actual result after these steps?

c:geo crashes

Expected result after these steps?

no crahs

Reproducible

Yes

c:geo Version

2024.05.12-NB-a1dd1ef

System information

No response

Additional Information

logcat-2024-05-12-21-28-34-2.txt

@murggel murggel added Bug Issues classified as a bug Unverified Issue not yet confirmed/reproduced or feature requests not yet checked for plausibility labels May 12, 2024
@murggel murggel changed the title [UnifiedMap] [nightly] crash on panning / zooming the map [UnifiedMap] [nightly] crash on panning / zooming / loading caches on map May 12, 2024
@murggel
Copy link
Contributor Author

murggel commented May 12, 2024

I sent the enhanced logfile to support-mail

@murggel murggel added the Prio - High A significant malfunction of a feature/function. High user impact. label May 12, 2024
@murggel
Copy link
Contributor Author

murggel commented May 12, 2024

Reproducible on mapping large lists and with live map

@MagpieFourtyTwo
Copy link

Hmmm ... also had a lot of UM crashes, but they only started yesterday (with 2024.05.12-NB), before (always with latest nightly) it was fine. Ok, admittedly not really perfect, every now and then a crash (mainly on immediate actions after starting UM, kinda reproducible if you do any actions before the map rendering is finished), but not as frequent as yesterday (nearly every 3 to 5 minutes). And it even happened while just driving on a route to a UDC, without any other action, the phone was just sitting in it's mounting bracket (see comment 2106899028).

And at the same times when c:geo crashed, Bluetooth went away, too, which is why I thought it might be a device issue. But today I drove the exact same route again (only in opposite direction and finished a bit earlier), with UM disabled, and both c:geo and the phone behaved fine. However, will try again this evening, then with UM activated ...

@MagpieFourtyTwo
Copy link

Ah, just seen: You reported the crashes with 2024.05.12-NB - but earlier UM crashes can also be found in Logcat ...
So, has the frequency of crashes changed with 2024.05.12-NB or why have you only reported them now?

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

Ah, just seen: You reported the crashes with 2024.05.12-NB - but earlier UM crashes can also be found in Logcat ...
So, has the frequency of crashes changed with 2024.05.12-NB or why have you only reported them now?

Because I don't update every day and they just happened with that update for me

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

Doesn't happen on Google-Maps

@MagpieFourtyTwo
Copy link

MagpieFourtyTwo commented May 13, 2024

On OSM it definitely does. And it seems to do something really bad, because it always takes some other apps with it.

Tested again this afternoon, drove about 100 kilometers with classic map, with no problems at all. On my way back home I switched to unified map and it took not even minutes till the first crash: screen turned black, as usual, and after a few seconds cgeo came back, but not with the map but with the details page of the last cache viewed - and two of my background apps were gone.

Tried this a second time, just shortly switched back to Android's home screen to restart my apps, but on return to cgeo via the last apps screen, it crashed again.

After it's rebirth it then even ran for a couple of minutes, but then crashed again.

Maybe understandable that after that I went back to classic map. Which is not only way faster but even as stable as I would expect it to be. ;)

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

I will try to debug into it tonight...

@moving-bits
Copy link
Member

Last exception I found in the logfile is this one:

05-11 23:05:19.344  3905  3905 E AndroidRuntime: Caused by: java.lang.NullPointerException: Attempt to invoke virtual method 'cgeo.geocaching.location.Viewport cgeo.geocaching.unifiedmap.AbstractMapFragment.getViewport()' on a null object reference
05-11 23:05:19.344  3905  3905 E AndroidRuntime: 	at cgeo.geocaching.unifiedmap.UnifiedMapActivity.refreshListChooser(UnifiedMapActivity.java:571)
05-11 23:05:19.344  3905  3905 E AndroidRuntime: 	at cgeo.geocaching.unifiedmap.UnifiedMapActivity.reloadCachesAndWaypoints(UnifiedMapActivity.java:477)
05-11 23:05:19.344  3905  3905 E AndroidRuntime: 	at cgeo.geocaching.unifiedmap.UnifiedMapActivity.onResume(UnifiedMapActivity.java:1188)
05-11 23:05:19.344  3905  3905 E AndroidRuntime: 	at android.app.Instrumentation.callActivityOnResume(Instrumentation.java:1476)
05-11 23:05:19.344  3905  3905 E AndroidRuntime: 	at android.app.Activity.performResume(Activity.java:8441)
05-11 23:05:19.344  3905  3905 E AndroidRuntime: 	at android.app.ActivityThread.performResumeActivity(ActivityThread.java:5200)

This seems to be a somewhat older codebase, before commit 39b5ccc / PR #15677.
Would it be possible that this log was generated from a end of April-version? (Source line of crash seems to indicate that to me, but I may be wrong.)
Can you reproduce the issue with current nightly?

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

With current master I can't redroduce it in emulator - I will install current nightly on my device and test it

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

This seems to be a somewhat older codebase, before commit 39b5ccc / PR #15677. Would it be possible that this log was generated from a end of April-version? (Source line of crash seems to indicate that to me, but I may be wrong.) Can you reproduce the issue with current nightly?

I downloaded the crashing version on 2024/05/12 at 7 am in the morning ...

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

With current master I can't redroduce it in emulator - I will install current nightly on my device and test it

Still crashes with current nightly

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

Is there a possibility to delete previous log entries, so only the entries of current session are logged / written in the log-file?

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

05-11 23:05:19.344 3905 3905 E AndroidRuntime: Caused by: java.lang.NullPointerException: Attempt to invoke virtual method 'cgeo.geocaching.location.Viewport cgeo.geocaching.unifiedmap.AbstractMapFragment.getViewport()' on a null object reference
05-11 23:05:19.344 3905 3905 E AndroidRuntime: at cgeo.geocaching.unifiedmap.UnifiedMapActivity.refreshListChooser(UnifiedMapActivity.java:571)
05-11 23:05:19.344 3905 3905 E AndroidRuntime: at cgeo.geocaching.unifiedmap.UnifiedMapActivity.reloadCachesAndWaypoints(UnifiedMapActivity.java:477)
05-11 23:05:19.344 3905 3905 E AndroidRuntime: at cgeo.geocaching.unifiedmap.UnifiedMapActivity.onResume(UnifiedMapActivity.java:1188)
05-11 23:05:19.344 3905 3905 E AndroidRuntime: at android.app.Instrumentation.callActivityOnResume(Instrumentation.java:1476)
05-11 23:05:19.344 3905 3905 E AndroidRuntime: at android.app.Activity.performResume(Activity.java:8441)
05-11 23:05:19.344 3905 3905 E AndroidRuntime: at a

Correct - those entries are with the version before ...

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

Logfile from today
logcat-2024-05-13-21-41-35-2.txt

There are some other messsages:
05-13 21:39:40.791 25993 25993 E cgeo : [main] GoogleV2GeoItemLayer.remove: can't remove item
...

05-13 21:41:23.561 20536 20536 I ToolbarWidgetWrapper: Progress display unsupported
05-13 21:41:23.574 20536 20536 E cgeo.geocachin: No package ID ff found for ID 0xffffffff.
05-13 21:41:23.585 20536 20536 W ListPreference: Setting a summary with a String formatting marker is no longer supported. You should use a SummaryProvider instead.
...

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

I just imported the settings from my device to the emulator - still no crash in emulator to reproduce...

@moving-bits
Copy link
Member

Is there a possibility to delete previous log entries, so only the entries of current session are logged / written in the log-file?

Not that I know of any specific option to achieve this. Logcat behavior known to me always deletes all old messages after an export, thus you would get only new ones on next run. (At least that's the behavior I observe when creating logs from within c:geo=

@moving-bits
Copy link
Member

Logfile from today logcat-2024-05-13-21-41-35-2.txt

There are some other messsages: 05-13 21:39:40.791 25993 25993 E cgeo : [main] GoogleV2GeoItemLayer.remove: can't remove item ...

05-13 21:41:23.561 20536 20536 I ToolbarWidgetWrapper: Progress display unsupported 05-13 21:41:23.574 20536 20536 E cgeo.geocachin: No package ID ff found for ID 0xffffffff. 05-13 21:41:23.585 20536 20536 W ListPreference: Setting a summary with a String formatting marker is no longer supported. You should use a SummaryProvider instead. ...

I can't find any new Java exceptions in here, only a couple of messages marked as "error". The error in GeoItemLayer.remove call seems to be logged only, but not leading to an actual crash (see GoogleV2GeoItemLayer.java line 144). Not sure where the "No package ID ff" error comes from. Other than that I don't see any reasons why it should crash. Strange 🤔

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

Is there a possibility to delete previous log entries, so only the entries of current session are logged / written in the log-file?

Not that I know of any specific option to achieve this. Logcat behavior known to me always deletes all old messages after an export, thus you would get only new ones on next run. (At least that's the behavior I observe when creating logs from within c:geo=

Unfortunately not in my case, the logs form 2024-04-29 are still written in the new log

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

I don't have an idea, why it does not happen in the emulator ....

I can try to install the debug-version on my device... and see what happen then

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

Here the COMPLETE log-output of launching current master from AndroidStudio on my device, no exception at the end, but c:geo crashed

2024-05-13 22:37:43.232 682-682 audit auditd E type=1400 audit(1715632663.223:222363): avc: granted { execute } for pid=25294 comm="install_server-" path="/data/data/cgeo.geocaching.developer/code_cache/install_server-75d1b755" dev="sda32" ino=1509339 scontext=u:r:runas_app:s0:c106,c258,c512,c768 tcontext=u:object_r:app_data_file:s0:c106,c258,c512,c768 tclass=file SEPF_SM-G980F_12_0001 audit_filtered
2024-05-13 22:37:43.899 25339-25339 USNET pid-25339 E USNET: appName: cgeo.geocaching.developer
---------------------------- PROCESS STARTED (25339) for package cgeo.geocaching.developer ----------------------------
2024-05-13 22:37:43.989 25339-25339 ActivityThread cgeo.geocaching.developer W Application cgeo.geocaching.developer is waiting for the debugger on port 8100...
2024-05-13 22:37:43.990 25339-25339 System.out cgeo.geocaching.developer I Sending WAIT chunk
2024-05-13 22:37:44.190 25339-25339 System.out cgeo.geocaching.developer I Debugger has connected
2024-05-13 22:37:44.190 25339-25339 System.out cgeo.geocaching.developer I waiting for debugger to settle...
2024-05-13 22:37:44.390 25339-25339 System.out cgeo.geocaching.developer I waiting for debugger to settle...
2024-05-13 22:37:44.591 25339-25339 System.out cgeo.geocaching.developer I waiting for debugger to settle...
2024-05-13 22:37:44.791 25339-25339 System.out cgeo.geocaching.developer I waiting for debugger to settle...
2024-05-13 22:37:44.991 25339-25339 System.out cgeo.geocaching.developer I waiting for debugger to settle...
2024-05-13 22:37:45.192 25339-25339 System.out cgeo.geocaching.developer I waiting for debugger to settle...
2024-05-13 22:37:45.392 25339-25339 System.out cgeo.geocaching.developer I waiting for debugger to settle...
2024-05-13 22:37:45.592 25339-25339 System.out cgeo.geocaching.developer I waiting for debugger to settle...
2024-05-13 22:37:45.792 25339-25339 System.out cgeo.geocaching.developer I waiting for debugger to settle...
2024-05-13 22:37:45.992 25339-25339 System.out cgeo.geocaching.developer I debugger has settled (1455)
2024-05-13 22:37:45.993 25339-25339 ActivityThread cgeo.geocaching.developer W Slow operation: 2015ms so far, now at handleBindApplication: Before HardwareRenderer
2024-05-13 22:37:45.997 25339-25339 ActivityThread cgeo.geocaching.developer W Slow operation: 2018ms so far, now at handleBindApplication: After HardwareRenderer
2024-05-13 22:37:46.001 25339-25339 nativeloader cgeo.geocaching.developer D Configuring clns-4 for other apk /system/framework/org.apache.http.legacy.jar. target_sdk_version=33, uses_libraries=ALL, library_path=/data/app/~~xblet-INJ__roxECSVJa3g==/cgeo.geocaching.developer-y3qOrR7aATtEwMAi9Um5HQ==/lib/arm64:/data/app/~~xblet-INJ__roxECSVJa3g==/cgeo.geocaching.developer-y3qOrR7aATtEwMAi9Um5HQ==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/cgeo.geocaching.developer
2024-05-13 22:37:46.001 25339-25339 nativeloader cgeo.geocaching.developer D Extending system_exposed_libraries: libperfsdk.performance.samsung.so:libneural.snap.samsung.so:libsecuresnap_hidl.snap.samsung.so:libsecuresnap_hidl.snap.samsung.so:libsnap_hidl.snap.samsung.so:libsnap_hidl.snap.samsung.so:lib_vnd_client.dk.samsung.so:lib_native_client.dk.samsung.so:lib_nativeJni.dk.samsung.so:libvesgraphicframework.videoeditor.samsung.so:libvesinterface.videoeditor.samsung.so:libvesframework.videoeditor.samsung.so:libhumantracking.arcsoft.so:libPortraitDistortionCorrection.arcsoft.so:libPortraitDistortionCorrectionCali.arcsoft.so:libface_landmark.arcsoft.so:libFacialStickerEngine.arcsoft.so:libveengine.arcsoft.so:lib_pet_detection.arcsoft.so:libhigh_res.arcsoft.so:libimage_enhancement.arcsoft.so:libsuperresolution.arcsoft.so:libFacialAttributeDetection.arcsoft.so:libss_jni.securestorage.samsung.so:libSDKRecognitionText.spensdk.samsung.so:libsce_v1.crypto.samsung.so:libhal.wsm.samsung.so:libeden_rt_stub.edensdk.samsung.so:libSFEffect.fonteffect.samsung.so:libBestComposition.polarr.so:libTra
2024-05-13 22:37:46.046 25339-25339 nativeloader cgeo.geocaching.developer D Configuring clns-5 for other apk /data/app/~~xblet-INJ__roxECSVJa3g==/cgeo.geocaching.developer-y3qOrR7aATtEwMAi9Um5HQ==/base.apk. target_sdk_version=33, uses_libraries=, library_path=/data/app/~~xblet-INJ__roxECSVJa3g==/cgeo.geocaching.developer-y3qOrR7aATtEwMAi9Um5HQ==/lib/arm64:/data/app/~~xblet-INJ__roxECSVJa3g==/cgeo.geocaching.developer-y3qOrR7aATtEwMAi9Um5HQ==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/cgeo.geocaching.developer
2024-05-13 22:37:46.061 25339-25339 GraphicsEnvironment cgeo.geocaching.developer V ANGLE Developer option for 'cgeo.geocaching.developer' set to: 'default'
2024-05-13 22:37:46.061 25339-25339 GraphicsEnvironment cgeo.geocaching.developer V App is not on the allowlist for updatable production driver.
2024-05-13 22:37:46.065 25339-25339 LoadedApk cgeo.geocaching.developer D LoadedApk::makeApplication() appContext.mOpPackageName=cgeo.geocaching.developer appContext.mBasePackageName=cgeo.geocaching.developer
2024-05-13 22:37:46.065 25339-25339 NetworkSecurityConfig cgeo.geocaching.developer D No Network Security Config specified, using platform default
2024-05-13 22:37:46.176 25339-25339 NetworkSecurityConfig cgeo.geocaching.developer D No Network Security Config specified, using platform default
2024-05-13 22:37:46.206 25339-25339 WM-WrkMgrInitializer cgeo.geocaching.developer D Initializing WorkManager with default configuration.
2024-05-13 22:37:46.234 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden method Landroid/view/WindowManagerGlobal;->getInstance()Landroid/view/WindowManagerGlobal; (unsupported, reflection, allowed)
2024-05-13 22:37:46.234 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden field Landroid/view/WindowManagerGlobal;->mViews:Ljava/util/ArrayList; (unsupported, reflection, allowed)
2024-05-13 22:37:46.234 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden field Landroid/app/ActivityThread;->mH:Landroid/app/ActivityThread$H; (unsupported, reflection, allowed)
2024-05-13 22:37:46.234 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden method Landroid/app/ActivityThread;->currentActivityThread()Landroid/app/ActivityThread; (unsupported, reflection, allowed)
2024-05-13 22:37:46.234 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden field Landroid/os/Handler;->mCallback:Landroid/os/Handler$Callback; (unsupported, reflection, allowed)
2024-05-13 22:37:46.234 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden field Landroid/util/Singleton;->mInstance:Ljava/lang/Object; (unsupported, reflection, allowed)
2024-05-13 22:37:46.234 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden method Landroid/util/Singleton;->get()Ljava/lang/Object; (unsupported, reflection, allowed)
2024-05-13 22:37:46.234 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden field Landroid/app/ActivityManager;->IActivityManagerSingleton:Landroid/util/Singleton; (unsupported, reflection, allowed)
2024-05-13 22:37:46.243 25339-25339 ActivityThread cgeo.geocaching.developer D handleBindApplication() --
2024-05-13 22:37:46.250 25339-25339 cgeo cgeo.geocaching.developer I [main] [CtxLog]ContentStorage.init:START
2024-05-13 22:37:46.255 25339-25339 cgeo cgeo.geocaching.developer I [main] [CtxLog]ContentStorage.init:END (6ms)
2024-05-13 22:37:46.272 25339-25420 Compatibil...geReporter cgeo.geocaching.developer D Compat change id reported: 160794467; UID 10618; state: ENABLED
2024-05-13 22:37:46.334 25339-25339 cgeo cgeo.geocaching.developer I [Log] Logging set: debug=false, minLevel=WARN, minAddCallerInfo=NONE, addCallerInfoMaxDepth=8, throwOnError=false, transactionSizes=false
2024-05-13 22:37:46.697 25339-25339 cgeo cgeo.geocaching.developer I [Log] Logging set: debug=false, minLevel=WARN, minAddCallerInfo=NONE, addCallerInfoMaxDepth=8, throwOnError=false, transactionSizes=false
2024-05-13 22:37:46.697 25339-25339 cgeo cgeo.geocaching.developer I [Log] No logging config file 'log-properties.txt' found at SD-Karte/Cgeo_debug/logfiles[PERSISTABLE_FOLDER(BASE)#1:p-content://com.android.externalstorage.documents/tree/3438-3261%3ACgeo_debug::/logfiles], using defaults
2024-05-13 22:37:46.697 25339-25339 cgeo cgeo.geocaching.developer I [main] ---------------- CGeoApplication: startup -------------
2024-05-13 22:37:46.697 25339-25339 cgeo cgeo.geocaching.developer E [main] c:geo version 2024.05.13-dcf0f7a developer build
2024-05-13 22:37:46.698 25339-25339 cgeo cgeo.geocaching.developer I [main] [CtxLog]CGeoApplication.onCreate:START
2024-05-13 22:37:46.711 25339-25339 cgeo cgeo.geocaching.developer I [main] [CtxLog]DataStore.init:START
2024-05-13 22:37:46.719 25339-25339 cgeo cgeo.geocaching.developer I [main] [DB] Current Database Version: 103
2024-05-13 22:37:46.722 25339-25339 cgeo cgeo.geocaching.developer I [main] [CtxLog]DataStore.init:END (11ms)
2024-05-13 22:37:46.753 25339-25339 cgeo cgeo.geocaching.developer I [main] LooperLogger: started for Thread: main
2024-05-13 22:37:46.755 25339-25339 System.err cgeo.geocaching.developer W SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
2024-05-13 22:37:46.755 25339-25339 System.err cgeo.geocaching.developer W SLF4J: Defaulting to no-operation (NOP) logger implementation
2024-05-13 22:37:46.755 25339-25339 System.err cgeo.geocaching.developer W SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
2024-05-13 22:37:46.755 25339-25339 cgeo cgeo.geocaching.developer I [main] [CtxLog]CGeoApplication.onCreate:END (58ms)
2024-05-13 22:37:46.762 25339-25451 OpenGLRenderer cgeo.geocaching.developer D RenderThread::requireGlContext()
2024-05-13 22:37:46.771 25339-25339 AppCompatDelegate cgeo.geocaching.developer D Checking for metadata for AppLocalesMetadataHolderService : Service not found
2024-05-13 22:37:46.777 25339-25451 OpenGLRenderer cgeo.geocaching.developer D RenderThread::setGrContext()
2024-05-13 22:37:47.037 25339-25339 DecorView cgeo.geocaching.developer I [INFO] isPopOver=false, config=true
2024-05-13 22:37:47.037 25339-25339 DecorView cgeo.geocaching.developer I updateCaptionType >> DecorView@fa55d00[], isFloating=false, isApplication=true, hasWindowControllerCallback=true, hasWindowDecorCaption=false
2024-05-13 22:37:47.038 25339-25339 DecorView cgeo.geocaching.developer D setCaptionType = 0, this = DecorView@fa55d00[]
2024-05-13 22:37:47.041 25339-25339 DecorView cgeo.geocaching.developer I getCurrentDensityDpi: from real metrics. densityDpi=480 msg=resources_loaded
2024-05-13 22:37:47.051 25339-25339 Choreographer cgeo.geocaching.developer I Skipped 49 frames! The application may be doing too much work on its main thread.
2024-05-13 22:37:47.093 25339-25421 LeakCanary cgeo.geocaching.developer D LeakCanary is currently disabled: Waiting for debugger to detach.
2024-05-13 22:37:47.101 25339-25339 DecorView cgeo.geocaching.developer I [INFO] isPopOver=false, config=true
2024-05-13 22:37:47.101 25339-25339 DecorView cgeo.geocaching.developer I updateCaptionType >> DecorView@20c22e3[], isFloating=false, isApplication=true, hasWindowControllerCallback=true, hasWindowDecorCaption=false
2024-05-13 22:37:47.101 25339-25339 DecorView cgeo.geocaching.developer D setCaptionType = 0, this = DecorView@20c22e3[]
2024-05-13 22:37:47.102 25339-25339 DecorView cgeo.geocaching.developer I getCurrentDensityDpi: from real metrics. densityDpi=480 msg=resources_loaded
2024-05-13 22:37:47.117 25339-25339 ToolbarWidgetWrapper cgeo.geocaching.developer I Progress display unsupported
2024-05-13 22:37:47.117 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden method Landroid/view/View;->computeFitSystemWindows(Landroid/graphics/Rect;Landroid/graphics/Rect;)Z (unsupported, reflection, allowed)
2024-05-13 22:37:47.117 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden method Landroid/view/ViewGroup;->makeOptionalFitsSystemWindows()V (unsupported, reflection, allowed)
2024-05-13 22:37:47.213 25339-25339 Compatibil...geReporter cgeo.geocaching.developer D Compat change id reported: 171228096; UID 10618; state: ENABLED
2024-05-13 22:37:47.511 25339-25339 cgeo cgeo.geocaching.developer I [main] [CtxLog]DataStore.cleanIfNeeded: cleans DB:START
2024-05-13 22:37:47.511 25339-25339 cgeo cgeo.geocaching.developer I [main] [CtxLog]DataStore.cleanIfNeeded: cleans DB:END (0ms)
2024-05-13 22:37:47.520 25339-25339 cgeo cgeo.geocaching.developer W [main] Chosen extCgeoDir /storage/emulated/0/Android/data/cgeo.geocaching/files is not an available external dir, falling back to default extCgeoDir
2024-05-13 22:37:47.524 25339-25339 cgeo cgeo.geocaching.developer I [main] LocalStorage: current Version: 3, expected Version: 3
2024-05-13 22:37:47.689 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: ignored. pkg=cgeo.geocaching.developer parent=null callers=com.android.internal.policy.DecorView.setVisibility:4295 android.app.ActivityThread.handleResumeActivity:5301 android.app.servertransaction.ResumeActivityItem.execute:54 android.app.servertransaction.ActivityTransactionItem.execute:45 android.app.servertransaction.TransactionExecutor.executeLifecycleState:176
2024-05-13 22:37:47.690 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I removeMultiSplitHandler: no exist. decor=DecorView@20c22e3[]
2024-05-13 22:37:47.698 25339-25339 ching.develope cgeo.geocaching.developer W Accessing hidden field Lcom/android/internal/policy/DecorView;->mWindow:Lcom/android/internal/policy/PhoneWindow; (unsupported, reflection, allowed)
2024-05-13 22:37:47.716 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I setView = com.android.internal.policy.DecorView@20c22e3 TM=true
2024-05-13 22:37:47.718 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: windowingMode=1 isFullscreen=true isPopOver=false isHidden=false skipActivityType=false isHandlerType=true this: DecorView@20c22e3[MainActivity]
2024-05-13 22:37:47.718 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I removeMultiSplitHandler: no exist. decor=DecorView@20c22e3[MainActivity]
2024-05-13 22:37:47.720 25339-25339 cgeo cgeo.geocaching.developer W [main] LooperLogger: long process time for >>>>> Dispatching to Handler (android.app.ActivityThread$H) {a8e0ea0} null: 159 (658ms, total:#10/959ms/avg 95ms), 0traces:
2024-05-13 22:37:47.770 25339-25469 cgeo cgeo.geocaching.developer W [network--2] OkapiClient.getUserInfo: error getting user info: 'Invalid access token: kkYvgZBUGGnMJd24eSJN.'
2024-05-13 22:37:48.006 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I Relayout returned: old=(0,0,1080,2400) new=(0,0,1080,2400) req=(1080,2400)0 dur=11 res=0x7 s={true 501319761136} ch=true fn=-1
2024-05-13 22:37:48.010 25339-25451 hw-ProcessState cgeo.geocaching.developer D Binder ioctl to enable oneway spam detection failed: Invalid argument
2024-05-13 22:37:48.011 25339-25451 OpenGLRenderer cgeo.geocaching.developer D eglCreateWindowSurface
2024-05-13 22:37:48.013 25339-25506 Gralloc4 cgeo.geocaching.developer I mapper 4.x is not supported
2024-05-13 22:37:48.013 25339-25506 Gralloc3 cgeo.geocaching.developer W mapper 3.x is not supported
2024-05-13 22:37:48.016 25339-25506 gralloc cgeo.geocaching.developer I Arm Module v1.0
2024-05-13 22:37:48.018 25339-25506 Gralloc4 cgeo.geocaching.developer W allocator 4.x is not supported
2024-05-13 22:37:48.018 25339-25506 Gralloc3 cgeo.geocaching.developer W allocator 3.x is not supported
2024-05-13 22:37:48.219 25339-25339 AbsListView cgeo.geocaching.developer D in onLayout changed
2024-05-13 22:37:48.231 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I [DP] dp(1) 1 android.view.ViewRootImpl.reportNextDraw:11387 android.view.ViewRootImpl.performTraversals:4185 android.view.ViewRootImpl.doTraversal:2911
2024-05-13 22:37:48.233 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer D Creating frameDrawingCallback nextDrawUseBlastSync=false reportNextDraw=true hasBlurUpdates=false
2024-05-13 22:37:48.233 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer D Creating frameCompleteCallback
2024-05-13 22:37:48.307 25339-25515 ViewRootIm...nActivity] cgeo.geocaching.developer D Received frameDrawingCallback frameNum=1. Creating transactionCompleteCallback=false
2024-05-13 22:37:48.355 25339-25451 ViewRootIm...nActivity] cgeo.geocaching.developer D Received frameCompleteCallback lastAcquiredFrameNum=1 lastAttemptedDrawFrameNum=1
2024-05-13 22:37:48.360 25339-25339 cgeo cgeo.geocaching.developer W [main] LooperLogger: long process time for >>>>> Dispatching to Handler (android.view.Choreographer$FrameHandler) {3ece1c7} android.view.Choreographer$FrameDisplayEventReceiver@f90f3f4: 0 (531ms, total:#45/1586ms/avg 35ms), 0traces:
2024-05-13 22:37:48.362 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I [DP] pdf(0) 1 android.view.ViewRootImpl.lambda$addFrameCompleteCallbackIfNeeded$3$ViewRootImpl:4987 android.view.ViewRootImpl$$ExternalSyntheticLambda16.run:6 android.os.Handler.handleCallback:938
2024-05-13 22:37:48.362 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I [DP] rdf()
2024-05-13 22:37:48.362 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer D reportDrawFinished (fn: -1)
2024-05-13 22:37:48.363 25339-25339 Choreographer cgeo.geocaching.developer I Skipped 31 frames! The application may be doing too much work on its main thread.
2024-05-13 22:37:48.525 1160-1204 WindowManager system_server E win=Window{652fd16 u0 Splash Screen cgeo.geocaching.developer EXITING} destroySurfaces: appStopped=false win.mWindowRemovalAllowed=true win.mRemoveOnExit=true win.mViewVisibility=8 caller=com.android.server.wm.ActivityRecord.destroySurfaces:5860 com.android.server.wm.ActivityRecord.destroySurfaces:5841 com.android.server.wm.WindowState.onExitAnimationDone:5982 com.android.server.wm.WindowStateAnimator.onAnimationFinished:242 com.android.server.wm.WindowState.onAnimationFinished:6235 com.android.server.wm.WindowContainer$$ExternalSyntheticLambda0.onAnimationFinished:2 com.android.server.wm.SurfaceAnimator.lambda$getFinishedCallback$0$SurfaceAnimator:113
2024-05-13 22:37:48.869 25339-25339 InsetsSourceConsumer cgeo.geocaching.developer D ensureControlAlpha: for ITYPE_NAVIGATION_BAR on cgeo.geocaching.developer/cgeo.geocaching.MainActivity
2024-05-13 22:37:48.870 25339-25339 InsetsSourceConsumer cgeo.geocaching.developer D ensureControlAlpha: for ITYPE_STATUS_BAR on cgeo.geocaching.developer/cgeo.geocaching.MainActivity
2024-05-13 22:37:48.895 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of androidx.fragment.app.FragmentManagerViewModel (androidx.fragment.app.FragmentManagerViewModel received ViewModel#onCleared() callback) with key b1150b31-ac78-4bf2-9802-7afac1ae1b3d
2024-05-13 22:37:48.895 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of leakcanary.internal.ViewModelClearedWatcher (leakcanary.internal.ViewModelClearedWatcher received ViewModel#onCleared() callback) with key 54227f8a-efcd-4e03-8818-ee6c562219a8
2024-05-13 22:37:48.896 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of androidx.lifecycle.SavedStateHandlesVM (androidx.lifecycle.SavedStateHandlesVM received ViewModel#onCleared() callback) with key 80c73397-3024-44e3-b6a3-11741de16f95
2024-05-13 22:37:48.897 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of androidx.lifecycle.ReportFragment (androidx.lifecycle.ReportFragment received Fragment#onDestroy() callback) with key fa13c1cd-39ae-410f-aa07-34a1120a5e8f
2024-05-13 22:37:48.898 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of cgeo.geocaching.SplashActivity (cgeo.geocaching.SplashActivity received Activity#onDestroy() callback) with key 6334bd78-1793-40b0-b2a7-3dbd6e4808c9
2024-05-13 22:37:48.900 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I MSG_WINDOW_FOCUS_CHANGED 1 1
2024-05-13 22:37:48.913 25339-25339 InputMethodManager cgeo.geocaching.developer D startInputInner - Id : 0
2024-05-13 22:37:48.913 25339-25339 InputMethodManager cgeo.geocaching.developer I startInputInner - mService.startInputOrWindowGainedFocus
2024-05-13 22:37:49.211 25339-25339 InputMethodManager cgeo.geocaching.developer D startInputInner - Id : 0
2024-05-13 22:37:52.249 25339-25548 ProfileInstaller cgeo.geocaching.developer D Installing profile for cgeo.geocaching.developer
2024-05-13 22:37:52.872 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I ViewPostIme pointer 0
2024-05-13 22:37:52.872 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: windowingMode=1 isFullscreen=true isPopOver=false isHidden=false skipActivityType=false isHandlerType=true this: DecorView@20c22e3[MainActivity]
2024-05-13 22:37:52.893 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: windowingMode=1 isFullscreen=true isPopOver=false isHidden=false skipActivityType=false isHandlerType=true this: DecorView@20c22e3[MainActivity]
2024-05-13 22:37:52.909 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: windowingMode=1 isFullscreen=true isPopOver=false isHidden=false skipActivityType=false isHandlerType=true this: DecorView@20c22e3[MainActivity]
2024-05-13 22:37:52.927 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: windowingMode=1 isFullscreen=true isPopOver=false isHidden=false skipActivityType=false isHandlerType=true this: DecorView@20c22e3[MainActivity]
2024-05-13 22:37:52.937 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: windowingMode=1 isFullscreen=true isPopOver=false isHidden=false skipActivityType=false isHandlerType=true this: DecorView@20c22e3[MainActivity]
2024-05-13 22:37:52.938 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I ViewPostIme pointer 1
2024-05-13 22:37:52.938 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: windowingMode=1 isFullscreen=true isPopOver=false isHidden=false skipActivityType=false isHandlerType=true this: DecorView@20c22e3[MainActivity]
2024-05-13 22:37:52.952 25339-25339 cgeo cgeo.geocaching.developer E [main] Launching UnifiedMap in live mode
2024-05-13 22:37:53.135 25339-25339 DecorView cgeo.geocaching.developer I [INFO] isPopOver=false, config=true
2024-05-13 22:37:53.135 25339-25339 DecorView cgeo.geocaching.developer I updateCaptionType >> DecorView@133eb72[], isFloating=false, isApplication=true, hasWindowControllerCallback=true, hasWindowDecorCaption=false
2024-05-13 22:37:53.136 25339-25339 DecorView cgeo.geocaching.developer D setCaptionType = 0, this = DecorView@133eb72[]
2024-05-13 22:37:53.138 25339-25339 DecorView cgeo.geocaching.developer I getCurrentDensityDpi: from real metrics. densityDpi=480 msg=resources_loaded
2024-05-13 22:37:53.149 25339-25339 ToolbarWidgetWrapper cgeo.geocaching.developer I Progress display unsupported
2024-05-13 22:37:53.824 25562-25562 USNET pid-25562 E USNET: appName: cgeo.geocaching.developer:brouter_service
2024-05-13 22:37:53.871 682-682 audit auditd E type=1400 audit(1715632673.867:222403): avc: granted { execute } for pid=25562 comm="re-initialized>" path="/data/data/cgeo.geocaching.developer/code_cache/startup_agents/75d1b755-agent.so" dev="sda32" ino=1508489 scontext=u:r:untrusted_app:s0:c106,c258,c512,c768 tcontext=u:object_r:app_data_file:s0:c106,c258,c512,c768 tclass=file SEPF_SM-G980F_12_0001 audit_filtered
---------------------------- PROCESS STARTED (25562) for package cgeo.geocaching.developer ----------------------------
2024-05-13 22:37:53.909 25562-25562 brouter_servic cgeo.geocaching.developer W Redefining intrinsic method java.lang.Thread java.lang.Thread.currentThread(). This may cause the unexpected use of the original definition of java.lang.Thread java.lang.Thread.currentThread()in methods that have already been compiled.
2024-05-13 22:37:53.909 25562-25562 brouter_servic cgeo.geocaching.developer W Redefining intrinsic method boolean java.lang.Thread.interrupted(). This may cause the unexpected use of the original definition of boolean java.lang.Thread.interrupted()in methods that have already been compiled.
2024-05-13 22:37:53.921 25562-25562 ActivityThread cgeo.geocaching.developer D handleBindApplication()++ app=cgeo.geocaching.developer:brouter_service
2024-05-13 22:37:53.922 25562-25562 Compatibil...geReporter cgeo.geocaching.developer D Compat change id reported: 171979766; UID 10618; state: ENABLED
2024-05-13 22:37:53.956 25562-25562 nativeloader cgeo.geocaching.developer D Configuring clns-4 for other apk /system/framework/org.apache.http.legacy.jar. target_sdk_version=33, uses_libraries=ALL, library_path=/data/app/~~xblet-INJ__roxECSVJa3g==/cgeo.geocaching.developer-y3qOrR7aATtEwMAi9Um5HQ==/lib/arm64:/data/app/~~xblet-INJ__roxECSVJa3g==/cgeo.geocaching.developer-y3qOrR7aATtEwMAi9Um5HQ==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/cgeo.geocaching.developer
2024-05-13 22:37:53.956 25562-25562 nativeloader cgeo.geocaching.developer D Extending system_exposed_libraries: libperfsdk.performance.samsung.so:libneural.snap.samsung.so:libsecuresnap_hidl.snap.samsung.so:libsecuresnap_hidl.snap.samsung.so:libsnap_hidl.snap.samsung.so:libsnap_hidl.snap.samsung.so:lib_vnd_client.dk.samsung.so:lib_native_client.dk.samsung.so:lib_nativeJni.dk.samsung.so:libvesgraphicframework.videoeditor.samsung.so:libvesinterface.videoeditor.samsung.so:libvesframework.videoeditor.samsung.so:libhumantracking.arcsoft.so:libPortraitDistortionCorrection.arcsoft.so:libPortraitDistortionCorrectionCali.arcsoft.so:libface_landmark.arcsoft.so:libFacialStickerEngine.arcsoft.so:libveengine.arcsoft.so:lib_pet_detection.arcsoft.so:libhigh_res.arcsoft.so:libimage_enhancement.arcsoft.so:libsuperresolution.arcsoft.so:libFacialAttributeDetection.arcsoft.so:libss_jni.securestorage.samsung.so:libSDKRecognitionText.spensdk.samsung.so:libsce_v1.crypto.samsung.so:libhal.wsm.samsung.so:libeden_rt_stub.edensdk.samsung.so:libSFEffect.fonteffect.samsung.so:libBestComposition.polarr.so:libTra
2024-05-13 22:37:53.970 25562-25562 nativeloader cgeo.geocaching.developer D Configuring clns-5 for other apk /data/app/~~xblet-INJ__roxECSVJa3g==/cgeo.geocaching.developer-y3qOrR7aATtEwMAi9Um5HQ==/base.apk. target_sdk_version=33, uses_libraries=, library_path=/data/app/~~xblet-INJ__roxECSVJa3g==/cgeo.geocaching.developer-y3qOrR7aATtEwMAi9Um5HQ==/lib/arm64:/data/app/~~xblet-INJ__roxECSVJa3g==/cgeo.geocaching.developer-y3qOrR7aATtEwMAi9Um5HQ==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/cgeo.geocaching.developer
2024-05-13 22:37:53.994 25562-25562 GraphicsEnvironment cgeo.geocaching.developer V ANGLE Developer option for 'cgeo.geocaching.developer' set to: 'default'
2024-05-13 22:37:53.995 25562-25562 GraphicsEnvironment cgeo.geocaching.developer V App is not on the allowlist for updatable production driver.
2024-05-13 22:37:54.001 25562-25562 LoadedApk cgeo.geocaching.developer D LoadedApk::makeApplication() appContext.mOpPackageName=cgeo.geocaching.developer appContext.mBasePackageName=cgeo.geocaching.developer
2024-05-13 22:37:54.001 25562-25562 NetworkSecurityConfig cgeo.geocaching.developer D No Network Security Config specified, using platform default
2024-05-13 22:37:54.037 25339-25511 cgeo cgeo.geocaching.developer W [network--8] OkapiClient.getUserInfo: error getting user info: 'Invalid access token: kkYvgZBUGGnMJd24eSJN.'
2024-05-13 22:37:54.037 25339-25449 SensorManager cgeo.geocaching.developer D registerListener :: 18, Samsung Rotation Vector, 200000, 0,
2024-05-13 22:37:54.057 25339-25349 ching.develope cgeo.geocaching.developer W Cleared Reference was only reachable from finalizer (only reported once)
2024-05-13 22:37:54.098 25339-25349 ching.develope cgeo.geocaching.developer I Background concurrent copying GC freed 227034(11MB) AllocSpace objects, 35(3024KB) LOS objects, 49% free, 8926KB/17MB, paused 194us,433us total 209.949ms
2024-05-13 22:37:54.246 25562-25562 NetworkSecurityConfig cgeo.geocaching.developer D No Network Security Config specified, using platform default
2024-05-13 22:37:54.247 25562-25562 ActivityThread cgeo.geocaching.developer D handleBindApplication() --
2024-05-13 22:37:54.251 25562-25562 cgeo cgeo.geocaching.developer I [main] [CtxLog]ContentStorage.init:START
2024-05-13 22:37:54.257 25562-25562 cgeo cgeo.geocaching.developer I [main] [CtxLog]ContentStorage.init:END (6ms)
2024-05-13 22:37:54.318 25339-25351 System cgeo.geocaching.developer W A resource failed to call close.
2024-05-13 22:37:54.318 25339-25351 System cgeo.geocaching.developer W A resource failed to call close.
2024-05-13 22:37:54.346 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: ignored. pkg=cgeo.geocaching.developer parent=null callers=com.android.internal.policy.DecorView.setVisibility:4295 android.app.ActivityThread.handleResumeActivity:5301 android.app.servertransaction.ResumeActivityItem.execute:54 android.app.servertransaction.ActivityTransactionItem.execute:45 android.app.servertransaction.TransactionExecutor.executeLifecycleState:176
2024-05-13 22:37:54.346 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I removeMultiSplitHandler: no exist. decor=DecorView@133eb72[]
2024-05-13 22:37:54.353 25562-25562 cgeo cgeo.geocaching.developer I [Log] Logging set: debug=false, minLevel=WARN, minAddCallerInfo=NONE, addCallerInfoMaxDepth=8, throwOnError=false, transactionSizes=false
2024-05-13 22:37:54.357 25339-25339 DecorView cgeo.geocaching.developer I notifyKeepScreenOnChanged: keepScreenOn=true
2024-05-13 22:37:54.358 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: ignored. pkg=cgeo.geocaching.developer parent=null callers=com.android.internal.policy.DecorView.hasMultiSplitHandlerInNonFreeformMode:891 com.android.internal.policy.DecorView.notifyKeepScreenOnChanged:885 android.view.ViewRootImpl.applyKeepScreenOnFlag:2932 android.view.ViewRootImpl.collectViewAttributes:2956 android.view.ViewRootImpl.setView:1545
2024-05-13 22:37:54.369 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer I setView = com.android.internal.policy.DecorView@133eb72 TM=true
2024-05-13 22:37:54.370 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: windowingMode=1 isFullscreen=true isPopOver=false isHidden=false skipActivityType=false isHandlerType=true this: DecorView@133eb72[UnifiedMapActivity]
2024-05-13 22:37:54.370 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I removeMultiSplitHandler: no exist. decor=DecorView@133eb72[UnifiedMapActivity]
2024-05-13 22:37:54.371 25339-25339 cgeo cgeo.geocaching.developer W [main] LooperLogger: long process time for >>>>> Dispatching to Handler (android.app.ActivityThread$H) {a8e0ea0} null: 159 (1259ms, total:#183/3879ms/avg 21ms), 0traces:
2024-05-13 22:37:54.374 25339-25339 Choreographer cgeo.geocaching.developer I Skipped 75 frames! The application may be doing too much work on its main thread.
2024-05-13 22:37:54.438 25339-25339 SurfaceView@8f0732a cgeo.geocaching.developer I onWindowVisibilityChanged(0) true org.oscim.android.MapView{8f0732a VFE...C.. ......I. 0,0-0,0 #7f0902ce app:id/mapViewVTM} of ViewRootImpl@25cb3a9[UnifiedMapActivity]
2024-05-13 22:37:54.597 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer I Relayout returned: old=(0,0,1080,2400) new=(0,0,1080,2400) req=(1080,2400)0 dur=8 res=0x7 s={true 501320188416} ch=true fn=-1
2024-05-13 22:37:54.598 25339-25451 OpenGLRenderer cgeo.geocaching.developer D eglCreateWindowSurface
2024-05-13 22:37:54.644 25339-25339 SurfaceView@8f0732a cgeo.geocaching.developer I windowStopped(false) true org.oscim.android.MapView{8f0732a VFE...C.. ......ID 0,0-1080,1842 #7f0902ce app:id/mapViewVTM} of ViewRootImpl@25cb3a9[UnifiedMapActivity]
2024-05-13 22:37:54.645 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer I [DP] dp(1) 1 android.view.ViewRootImpl.reportNextDraw:11387 android.view.ViewRootImpl.performTraversals:4185 android.view.ViewRootImpl.doTraversal:2911
2024-05-13 22:37:54.648 25339-25339 SurfaceView@8f0732a cgeo.geocaching.developer I pST: sr = Rect(0, 246 - 1080, 2088) sw = 1080 sh = 1842
2024-05-13 22:37:54.648 25339-25339 SurfaceView@8f0732a cgeo.geocaching.developer I onSSPAndSRT: pl = 0 pt = 246 sx = 1.0 sy = 1.0
2024-05-13 22:37:54.648 25339-25339 SurfaceView@8f0732a cgeo.geocaching.developer I pST: mTmpTransaction.apply, mTmpTransaction = android.view.SurfaceControl$Transaction@16cd919
2024-05-13 22:37:54.649 25339-25339 SurfaceView@8f0732a cgeo.geocaching.developer I updateSurface: mVisible = true mSurface.isValid() = true
2024-05-13 22:37:54.649 25339-25339 SurfaceView@8f0732a cgeo.geocaching.developer I updateSurface: mSurfaceCreated = false surfaceChanged = true visibleChanged = true
2024-05-13 22:37:54.649 25339-25339 SurfaceView@8f0732a cgeo.geocaching.developer I surfaceCreated 1 #8 org.oscim.android.MapView{8f0732a VFE...C.. ......ID 0,0-1080,1842 #7f0902ce app:id/mapViewVTM}
2024-05-13 22:37:54.650 25339-25339 SurfaceView@8f0732a cgeo.geocaching.developer I surfaceChanged (1080,1842) 1 #8 org.oscim.android.MapView{8f0732a VFE...C.. ......ID 0,0-1080,1842 #7f0902ce app:id/mapViewVTM}
2024-05-13 22:37:54.651 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer I [DP] dp(2) 1 android.view.SurfaceView.updateSurface:1375 android.view.SurfaceView.lambda$new$1$SurfaceView:254 android.view.SurfaceView$$ExternalSyntheticLambda2.onPreDraw:2
2024-05-13 22:37:54.659 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer D Creating frameDrawingCallback nextDrawUseBlastSync=false reportNextDraw=true hasBlurUpdates=false
2024-05-13 22:37:54.660 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer D Creating frameCompleteCallback
2024-05-13 22:37:54.660 25562-25562 cgeo cgeo.geocaching.developer I [Log] Logging set: debug=false, minLevel=WARN, minAddCallerInfo=NONE, addCallerInfoMaxDepth=8, throwOnError=false, transactionSizes=false
2024-05-13 22:37:54.660 25562-25562 cgeo cgeo.geocaching.developer I [Log] No logging config file 'log-properties.txt' found at SD-Karte/Cgeo_debug/logfiles[PERSISTABLE_FOLDER(BASE)#1:p-content://com.android.externalstorage.documents/tree/3438-3261%3ACgeo_debug::/logfiles], using defaults
2024-05-13 22:37:54.661 25562-25562 cgeo cgeo.geocaching.developer I [main] ---------------- CGeoApplication: startup -------------
2024-05-13 22:37:54.661 25562-25562 cgeo cgeo.geocaching.developer E [main] c:geo version 2024.05.13-dcf0f7a developer build
2024-05-13 22:37:54.661 25562-25562 cgeo cgeo.geocaching.developer I [main] [CtxLog]CGeoApplication.onCreate:START
2024-05-13 22:37:54.679 25562-25562 cgeo cgeo.geocaching.developer I [main] [CtxLog]DataStore.init:START
2024-05-13 22:37:54.681 25339-25515 SurfaceView@8f0732a cgeo.geocaching.developer I uSP: rtp = Rect(0, 246 - 1080, 2088) rtsw = 1080 rtsh = 1842
2024-05-13 22:37:54.682 25339-25515 SurfaceView@8f0732a cgeo.geocaching.developer I onSSPAndSRT: pl = 0 pt = 246 sx = 1.0 sy = 1.0
2024-05-13 22:37:54.683 25339-25515 SurfaceView@8f0732a cgeo.geocaching.developer I aOrMT: uB = true t = android.view.SurfaceControl$Transaction@99d028c fN = 1 android.view.SurfaceView.access$500:124 android.view.SurfaceView$SurfaceViewPositionUpdateListener.positionChanged:1728 android.graphics.RenderNode$CompositePositionUpdateListener.positionChanged:319
2024-05-13 22:37:54.683 25339-25515 SurfaceView@8f0732a cgeo.geocaching.developer I aOrMT: vR.mWNT, vR = ViewRootImpl@25cb3a9[UnifiedMapActivity]
2024-05-13 22:37:54.685 25339-25515 ViewRootIm...pActivity] cgeo.geocaching.developer I mWNT: t = android.view.SurfaceControl$Transaction@99d028c fN = 1 android.view.SurfaceView.applyOrMergeTransaction:1628 android.view.SurfaceView.access$500:124 android.view.SurfaceView$SurfaceViewPositionUpdateListener.positionChanged:1728
2024-05-13 22:37:54.685 25339-25515 ViewRootIm...pActivity] cgeo.geocaching.developer I mWNT: merge t to BBQ
2024-05-13 22:37:54.685 25339-25515 ViewRootIm...pActivity] cgeo.geocaching.developer D Received frameDrawingCallback frameNum=1. Creating transactionCompleteCallback=false
2024-05-13 22:37:54.692 25562-25562 cgeo cgeo.geocaching.developer I [main] [DB] Current Database Version: 103
2024-05-13 22:37:54.693 25562-25562 cgeo cgeo.geocaching.developer I [main] [CtxLog]DataStore.init:END (14ms)
2024-05-13 22:37:54.694 25339-25451 ViewRootIm...pActivity] cgeo.geocaching.developer D Received frameCompleteCallback lastAcquiredFrameNum=1 lastAttemptedDrawFrameNum=1
2024-05-13 22:37:54.702 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer I [DP] pdf(1) 1 android.view.ViewRootImpl.lambda$addFrameCompleteCallbackIfNeeded$3$ViewRootImpl:4987 android.view.ViewRootImpl$$ExternalSyntheticLambda16.run:6 android.os.Handler.handleCallback:938
2024-05-13 22:37:54.702 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer D pendingDrawFinished. Waiting on draw reported mDrawsNeededToReport=1
2024-05-13 22:37:54.705 25339-25357 OpenGLRenderer cgeo.geocaching.developer I Davey! duration=1570ms; Flags=1, FrameTimelineVsyncId=13305151, IntendedVsync=524638497538971, Vsync=524639747538921, InputEventId=0, HandleInputStart=524639748413592, AnimationStart=524639748420015, PerformTraversalsStart=524639753031053, DrawStart=524640033967399, FrameDeadline=524638514205637, FrameInterval=524639748245207, FrameStartTime=16666666, SyncQueued=524640054239630, SyncStart=524640054405822, IssueDrawCommandsStart=524640054882515, SwapBuffers=524640066111938, FrameCompleted=524640067761976, DequeueBufferDuration=1931692, QueueBufferDuration=985846, GpuCompleted=524640067330015, SwapBuffersCompleted=524640067761976, DisplayPresentTime=0,
2024-05-13 22:37:54.705 25339-25357 OpenGLRenderer cgeo.geocaching.developer I Davey! duration=1576ms; Flags=1, FrameTimelineVsyncId=13305151, IntendedVsync=524638497538971, Vsync=524639747538921, InputEventId=0, HandleInputStart=524639748413592, AnimationStart=524639748420015, PerformTraversalsStart=524639753031053, DrawStart=524640070149630, FrameDeadline=524638514205637, FrameInterval=524639748245207, FrameStartTime=16666666, SyncQueued=524640071773899, SyncStart=524640071864745, IssueDrawCommandsStart=524640071990015, SwapBuffers=524640073174207, FrameCompleted=524640074220092, DequeueBufferDuration=20231, QueueBufferDuration=556500, GpuCompleted=524640074157822, SwapBuffersCompleted=524640074220092, DisplayPresentTime=0,
2024-05-13 22:37:54.748 25562-25562 cgeo cgeo.geocaching.developer I [main] LooperLogger: started for Thread: main
2024-05-13 22:37:54.750 25562-25562 System.err cgeo.geocaching.developer W SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
2024-05-13 22:37:54.750 25562-25562 System.err cgeo.geocaching.developer W SLF4J: Defaulting to no-operation (NOP) logger implementation
2024-05-13 22:37:54.750 25562-25562 System.err cgeo.geocaching.developer W SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
2024-05-13 22:37:54.751 25562-25562 cgeo cgeo.geocaching.developer I [main] [CtxLog]CGeoApplication.onCreate:END (90ms)
2024-05-13 22:37:54.769 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I MSG_WINDOW_FOCUS_CHANGED 0 1
2024-05-13 22:37:55.081 25339-25339 InsetsSourceConsumer cgeo.geocaching.developer D ensureControlAlpha: for ITYPE_NAVIGATION_BAR on cgeo.geocaching.developer/cgeo.geocaching.unifiedmap.UnifiedMapActivity
2024-05-13 22:37:55.082 25339-25339 InsetsSourceConsumer cgeo.geocaching.developer D ensureControlAlpha: for ITYPE_STATUS_BAR on cgeo.geocaching.developer/cgeo.geocaching.unifiedmap.UnifiedMapActivity
2024-05-13 22:37:55.130 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer I [DP] pdf(0) 1 android.view.SurfaceView.notifyDrawFinished:599 android.view.SurfaceView.performDrawFinished:586 android.view.SurfaceView.$r8$lambda$st27mCkd9jfJkTrN_P3qIGKX6NY:0
2024-05-13 22:37:55.131 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer I [DP] rdf()
2024-05-13 22:37:55.131 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer D reportDrawFinished (fn: -1)
2024-05-13 22:37:55.538 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:1(1), REMOVES:0(0), REPLACES:0(0)[l:0,s:0]
2024-05-13 22:37:55.556 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:nonClickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:2(2), REMOVES:0(0), REPLACES:0(0)[l:1,s:1]
2024-05-13 22:37:56.202 25339-25339 cgeo cgeo.geocaching.developer W [main] LooperLogger: long process time for >>>>> Dispatching to Handler (android.view.Choreographer$FrameHandler) {3ece1c7} android.view.Choreographer$FrameDisplayEventReceiver@f90f3f4: 0 (642ms, total:#287/5637ms/avg 19ms), 0traces:
2024-05-13 22:37:56.206 25339-25339 ViewRootIm...pActivity] cgeo.geocaching.developer I MSG_WINDOW_FOCUS_CHANGED 1 1
2024-05-13 22:37:56.233 25339-25356 OpenGLRenderer cgeo.geocaching.developer I Davey! duration=1031ms; Flags=0, FrameTimelineVsyncId=13305218, IntendedVsync=524640557087999, Vsync=524640923754651, InputEventId=0, HandleInputStart=524640932845591, AnimationStart=524640932857937, PerformTraversalsStart=524640933987476, DrawStart=524641555184552, FrameDeadline=524640573754665, FrameInterval=524640932749668, FrameStartTime=16666666, SyncQueued=524641572352514, SyncStart=524641572620783, IssueDrawCommandsStart=524641573049206, SwapBuffers=524641585179552, FrameCompleted=524641588372783, DequeueBufferDuration=35769, QueueBufferDuration=2037270, GpuCompleted=524641586391975, SwapBuffersCompleted=524641588372783, DisplayPresentTime=0,
2024-05-13 22:37:56.270 25339-25339 InputMethodManager cgeo.geocaching.developer D startInputInner - Id : 0
2024-05-13 22:37:56.270 25339-25339 InputMethodManager cgeo.geocaching.developer I startInputInner - mService.startInputOrWindowGainedFocus
2024-05-13 22:37:56.323 25339-25339 Choreographer cgeo.geocaching.developer I Skipped 45 frames! The application may be doing too much work on its main thread.
2024-05-13 22:37:56.339 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I handleAppVisibility mAppVisible=true visible=false
2024-05-13 22:37:56.346 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I stopped(true) old=false
2024-05-13 22:37:56.350 25339-25357 OpenGLRenderer cgeo.geocaching.developer I Davey! duration=776ms; Flags=0, FrameTimelineVsyncId=13305266, IntendedVsync=524640939126525, Vsync=524641689126495, InputEventId=0, HandleInputStart=524641697487206, AnimationStart=524641697494744, PerformTraversalsStart=524641698555821, DrawStart=524641700273937, FrameDeadline=524640972459857, FrameInterval=524641697189283, FrameStartTime=16666666, SyncQueued=524641706686206, SyncStart=524641706874283, IssueDrawCommandsStart=524641707309090, SwapBuffers=524641712934783, FrameCompleted=524641715707090, DequeueBufferDuration=48654, QueueBufferDuration=1269538, GpuCompleted=524641714565321, SwapBuffersCompleted=524641715707090, DisplayPresentTime=0,
2024-05-13 22:37:56.374 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I isMultiSplitHandlerRequested: windowingMode=1 isFullscreen=true isPopOver=false isHidden=false skipActivityType=false isHandlerType=true this: DecorView@20c22e3[MainActivity]
2024-05-13 22:37:56.374 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I removeMultiSplitHandler: no exist. decor=DecorView@20c22e3[MainActivity]
2024-05-13 22:37:56.376 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of androidx.fragment.app.FragmentManagerViewModel (androidx.fragment.app.FragmentManagerViewModel received ViewModel#onCleared() callback) with key 0c9bf379-aac5-4268-89df-fcc9d2bc0d1d
2024-05-13 22:37:56.377 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of leakcanary.internal.ViewModelClearedWatcher (leakcanary.internal.ViewModelClearedWatcher received ViewModel#onCleared() callback) with key d127871b-7757-4dc7-bef0-33fe61cf5827
2024-05-13 22:37:56.377 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of androidx.lifecycle.SavedStateHandlesVM (androidx.lifecycle.SavedStateHandlesVM received ViewModel#onCleared() callback) with key 1303d321-d853-4911-81dd-15fb6c469cb1
2024-05-13 22:37:56.378 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of androidx.lifecycle.ReportFragment (androidx.lifecycle.ReportFragment received Fragment#onDestroy() callback) with key 7a8f14f9-dda7-464f-bf63-7ad3c8f29786
2024-05-13 22:37:56.379 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of cgeo.geocaching.MainActivity (cgeo.geocaching.MainActivity received Activity#onDestroy() callback) with key a38aba55-b5f7-4a25-90de-b30ff54f579a
2024-05-13 22:37:56.383 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of android.widget.RelativeLayout (cgeo.geocaching.StatusFragment received Fragment#onDestroyView() callback (references to its views should be cleared to prevent leaks)) with key 00161fa0-1861-4b39-9f56-5916f3087cb2
2024-05-13 22:37:56.384 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of leakcanary.internal.ViewModelClearedWatcher (leakcanary.internal.ViewModelClearedWatcher received ViewModel#onCleared() callback) with key 6c329df4-0803-4039-9924-b860c4451852
2024-05-13 22:37:56.385 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of androidx.loader.app.LoaderManagerImpl$LoaderViewModel (androidx.loader.app.LoaderManagerImpl$LoaderViewModel received ViewModel#onCleared() callback) with key 2dc7c87c-8c33-403f-bf7e-3ee64f4ae723
2024-05-13 22:37:56.386 25339-25339 LeakCanary cgeo.geocaching.developer D Watching instance of cgeo.geocaching.StatusFragment (cgeo.geocaching.StatusFragment received Fragment#onDestroy() callback) with key 3acf4d80-a2d4-4937-bbb2-c9eea1e95fd6
2024-05-13 22:37:56.437 25339-25339 MSHandlerLifeCycle cgeo.geocaching.developer I removeMultiSplitHandler: no exist. decor=DecorView@20c22e3[MainActivity]
2024-05-13 22:37:56.439 25339-25451 OpenGLRenderer cgeo.geocaching.developer D setSurface called with nullptr
2024-05-13 22:37:56.439 25339-25451 OpenGLRenderer cgeo.geocaching.developer D setSurface() destroyed EGLSurface
2024-05-13 22:37:56.439 25339-25451 OpenGLRenderer cgeo.geocaching.developer D destroyEglSurface
2024-05-13 22:37:56.441 25339-25339 ViewRootIm...nActivity] cgeo.geocaching.developer I dispatchDetachedFromWindow
2024-05-13 22:37:56.445 25339-25339 InputTransport cgeo.geocaching.developer D Input channel destroyed: '74c4715', fd=120
2024-05-13 22:37:56.503 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:nonClickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:0(2), REMOVES:0(0), REPLACES:1(1)[l:1,s:1]
2024-05-13 22:37:56.655 25339-25350 InputTransport cgeo.geocaching.developer D Input channel destroyed: 'ClientS', fd=161
2024-05-13 22:37:56.657 25339-25451 OpenGLRenderer cgeo.geocaching.developer D setSurface called with nullptr
2024-05-13 22:37:56.670 25339-25351 System cgeo.geocaching.developer W A resource failed to call close.
2024-05-13 22:37:58.721 25339-25495 cgeo cgeo.geocaching.developer I [network--4] GCWEBAPI: request: https://www.geocaching.com/api/proxy/web/search/v2?box=49.21181%2C8.684735%2C48.378623%2C9.426315&rad=16000&origin=48.795216%2C9.055525&sa=1&properties=callernote&take=500&skip=0&sort=distance&asc=true&app=cgeo
2024-05-13 22:38:02.764 25339-25339 cgeo cgeo.geocaching.developer W [main] LooperLogger: long process time for >>>>> Dispatching to Handler (android.os.Handler) {4afca74} androidx.lifecycle.LiveData$1@bff8e34: 0 (4066ms, total:#492/10714ms/avg 21ms), 0traces:
2024-05-13 22:38:02.769 25339-25339 Choreographer cgeo.geocaching.developer I Skipped 243 frames! The application may be doing too much work on its main thread.
2024-05-13 22:38:02.794 25339-25354 OpenGLRenderer cgeo.geocaching.developer I Davey! duration=4075ms; Flags=0, FrameTimelineVsyncId=13305579, IntendedVsync=524644078541283, Vsync=524648128541121, InputEventId=0, HandleInputStart=524648143199663, AnimationStart=524648143205586, PerformTraversalsStart=524648143769624, DrawStart=524648144290893, FrameDeadline=524644111874615, FrameInterval=524648143068163, FrameStartTime=16666666, SyncQueued=524648145405124, SyncStart=524648145490970, IssueDrawCommandsStart=524648145779124, SwapBuffers=524648148449124, FrameCompleted=524648153930547, DequeueBufferDuration=34731, QueueBufferDuration=1233884, GpuCompleted=524648153574663, SwapBuffersCompleted=524648153930547, DisplayPresentTime=-1,
2024-05-13 22:38:02.813 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:35(36), REMOVES:0(0), REPLACES:0(0)[l:1,s:28]
2024-05-13 22:38:03.430 25339-25339 ching.develope cgeo.geocaching.developer W Long monitor contention with owner GLThread 3514 (25580) at void org.oscim.renderer.BucketRenderer.compile()(BucketRenderer.java:164) waiters=0 in void org.oscim.layers.marker.MarkerRenderer.populate(int) for 555ms
2024-05-13 22:38:03.431 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:28(64), REMOVES:0(0), REPLACES:0(0)[l:1,s:50]
2024-05-13 22:38:03.432 25339-25339 cgeo cgeo.geocaching.developer W [main] LooperLogger: long process time for >>>>> Dispatching to Handler (android.os.Handler) {b904eb1} io.reactivex.rxjava3.android.schedulers.HandlerScheduler$ScheduledRunnable@1793ea4: 0 (597ms, total:#507/11373ms/avg 22ms), 0traces:
2024-05-13 22:38:03.433 25339-25339 Choreographer cgeo.geocaching.developer I Skipped 35 frames! The application may be doing too much work on its main thread.
2024-05-13 22:38:03.848 25339-25339 ching.develope cgeo.geocaching.developer W Long monitor contention with owner GLThread 3514 (25580) at void org.oscim.renderer.BucketRenderer.compile()(BucketRenderer.java:164) waiters=0 in void org.oscim.layers.marker.MarkerRenderer.populate(int) for 368ms
2024-05-13 22:38:03.848 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:33(97), REMOVES:0(0), REPLACES:0(0)[l:1,s:73]
2024-05-13 22:38:03.898 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:29(126), REMOVES:0(0), REPLACES:0(0)[l:1,s:96]
2024-05-13 22:38:04.790 25339-25339 ching.develope cgeo.geocaching.developer W Long monitor contention with owner GLThread 3514 (25580) at void org.oscim.renderer.BucketRenderer.compile()(BucketRenderer.java:164) waiters=0 in void org.oscim.layers.marker.MarkerRenderer.populate(int) for 845ms
2024-05-13 22:38:04.791 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:17(143), REMOVES:0(0), REPLACES:0(0)[l:1,s:111]
2024-05-13 22:38:04.791 25339-25339 cgeo cgeo.geocaching.developer W [main] LooperLogger: long process time for >>>>> Dispatching to Handler (android.os.Handler) {b904eb1} io.reactivex.rxjava3.android.schedulers.HandlerScheduler$ScheduledRunnable@bd39e7: 0 (889ms, total:#517/12729ms/avg 24ms), 0traces:
2024-05-13 22:38:04.792 25339-25339 Choreographer cgeo.geocaching.developer I Skipped 53 frames! The application may be doing too much work on its main thread.
2024-05-13 22:38:04.805 25339-25354 OpenGLRenderer cgeo.geocaching.developer I Davey! duration=896ms; Flags=0, FrameTimelineVsyncId=13305628, IntendedVsync=524649277510884, Vsync=524650160844182, InputEventId=0, HandleInputStart=524650166090430, AnimationStart=524650166094315, PerformTraversalsStart=524650166565738, DrawStart=524650166908161, FrameDeadline=524649310844216, FrameInterval=524650165994123, FrameStartTime=16666666, SyncQueued=524650167383815, SyncStart=524650167439738, IssueDrawCommandsStart=524650167708084, SwapBuffers=524650169617623, FrameCompleted=524650173961661, DequeueBufferDuration=33076, QueueBufferDuration=1204885, GpuCompleted=524650172415469, SwapBuffersCompleted=524650173961661, DisplayPresentTime=-4294967296,
2024-05-13 22:38:05.038 25339-25349 ching.develope cgeo.geocaching.developer I NativeAlloc concurrent copying GC freed 167458(6459KB) AllocSpace objects, 1(192KB) LOS objects, 50% free, 19MB/38MB, paused 85us,290us total 147.279ms
2024-05-13 22:38:05.175 25339-25339 ching.develope cgeo.geocaching.developer W Long monitor contention with owner GLThread 3514 (25580) at void org.oscim.renderer.BucketRenderer.compile()(BucketRenderer.java:164) waiters=0 in void org.oscim.layers.marker.MarkerRenderer.populate(int) for 334ms
2024-05-13 22:38:05.175 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:13(156), REMOVES:0(0), REPLACES:0(0)[l:1,s:123]
2024-05-13 22:38:05.367 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:17(173), REMOVES:0(0), REPLACES:0(0)[l:1,s:134]
2024-05-13 22:38:05.507 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:24(197), REMOVES:0(0), REPLACES:0(0)[l:1,s:148]
2024-05-13 22:38:05.824 25339-25339 ching.develope cgeo.geocaching.developer W Long monitor contention with owner GLThread 3514 (25580) at void org.oscim.renderer.BucketRenderer.compile()(BucketRenderer.java:164) waiters=0 in void org.oscim.layers.marker.MarkerRenderer.populate(int) for 270ms
2024-05-13 22:38:05.825 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:19(216), REMOVES:0(0), REPLACES:0(0)[l:1,s:163]
2024-05-13 22:38:06.232 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:17(233), REMOVES:0(0), REPLACES:0(0)[l:1,s:175]
2024-05-13 22:38:06.438 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:15(248), REMOVES:0(0), REPLACES:0(0)[l:1,s:184]
---------------------------- PROCESS ENDED (22448) for package cgeo.geocaching ----------------------------
2024-05-13 22:38:06.719 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:13(261), REMOVES:0(0), REPLACES:0(0)[l:1,s:193]
2024-05-13 22:38:07.037 25339-25339 ching.develope cgeo.geocaching.developer W Long monitor contention with owner GLThread 3514 (25580) at void org.oscim.renderer.BucketRenderer.compile()(BucketRenderer.java:164) waiters=0 in void org.oscim.layers.marker.MarkerRenderer.populate(int) for 266ms
2024-05-13 22:38:07.038 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:13(274), REMOVES:0(0), REPLACES:0(0)[l:1,s:200]
2024-05-13 22:38:07.175 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:16(290), REMOVES:0(0), REPLACES:0(0)[l:1,s:210]
2024-05-13 22:38:07.403 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:11(301), REMOVES:0(0), REPLACES:0(0)[l:1,s:218]
2024-05-13 22:38:07.577 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:13(314), REMOVES:0(0), REPLACES:0(0)[l:1,s:230]
2024-05-13 22:38:07.968 25339-25339 ching.develope cgeo.geocaching.developer W Long monitor contention with owner GLThread 3514 (25580) at void org.oscim.renderer.BucketRenderer.compile()(BucketRenderer.java:164) waiters=0 in void org.oscim.layers.marker.MarkerRenderer.populate(int) for 345ms
2024-05-13 22:38:07.969 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:10(324), REMOVES:0(0), REPLACES:0(0)[l:1,s:238]
2024-05-13 22:38:08.314 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:7(331), REMOVES:0(0), REPLACES:0(0)[l:1,s:244]
2024-05-13 22:38:08.431 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:15(346), REMOVES:0(0), REPLACES:0(0)[l:1,s:250]
2024-05-13 22:38:08.455 25339-25339 InputMethodManager cgeo.geocaching.developer D startInputInner - Id : 0
2024-05-13 22:38:08.455 25339-25339 InputMethodManager cgeo.geocaching.developer I startInputInner - mService.startInputOrWindowGainedFocus
2024-05-13 22:38:08.465 25339-25339 InputTransport cgeo.geocaching.developer D Input channel destroyed: 'ClientS', fd=138
2024-05-13 22:38:08.615 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:9(355), REMOVES:0(0), REPLACES:0(0)[l:1,s:257]
2024-05-13 22:38:08.618 25339-25339 InputMethodManager cgeo.geocaching.developer D startInputInner - Id : 0
2024-05-13 22:38:08.618 25339-25339 InputMethodManager cgeo.geocaching.developer I startInputInner - mService.startInputOrWindowGainedFocus
2024-05-13 22:38:08.886 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:15(370), REMOVES:0(0), REPLACES:0(0)[l:1,s:263]
2024-05-13 22:38:09.199 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:13(383), REMOVES:0(0), REPLACES:0(0)[l:1,s:272]
2024-05-13 22:38:09.444 25339-25339 ching.develope cgeo.geocaching.developer W Long monitor contention with owner GLThread 3514 (25580) at void org.oscim.renderer.BucketRenderer.compile()(BucketRenderer.java:164) waiters=0 in void org.oscim.layers.marker.MarkerRenderer.populate(int) for 186ms
2024-05-13 22:38:09.446 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:18(401), REMOVES:0(0), REPLACES:0(0)[l:1,s:277]
2024-05-13 22:38:09.586 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:7(408), REMOVES:0(0), REPLACES:0(0)[l:1,s:281]
2024-05-13 22:38:09.766 25339-25339 ching.develope cgeo.geocaching.developer W Long monitor contention with owner GLThread 3514 (25580) at void org.oscim.renderer.BucketRenderer.compile()(BucketRenderer.java:164) waiters=0 in void org.oscim.layers.marker.MarkerRenderer.populate(int) for 104ms
2024-05-13 22:38:09.766 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:4(412), REMOVES:0(0), REPLACES:0(0)[l:1,s:284]
2024-05-13 22:38:09.852 25339-25349 ching.develope cgeo.geocaching.developer I Background concurrent copying GC freed 516588(18MB) AllocSpace objects, 0(0B) LOS objects, 49% free, 19MB/39MB, paused 91us,148us total 161.954ms
2024-05-13 22:38:09.883 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:18(430), REMOVES:0(0), REPLACES:0(0)[l:1,s:293]
2024-05-13 22:38:10.196 25339-25339 ching.develope cgeo.geocaching.developer W Long monitor contention with owner GLThread 3514 (25580) at void org.oscim.renderer.BucketRenderer.compile()(BucketRenderer.java:164) waiters=0 in void org.oscim.layers.marker.MarkerRenderer.populate(int) for 257ms
---------------------------- PROCESS ENDED (25562) for package cgeo.geocaching.developer ----------------------------
2024-05-13 22:38:10.196 25339-25339 cgeo cgeo.geocaching.developer I [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):BATCH-END - ADDS:3(433), REMOVES:0(0), REPLACES:0(0)[l:1,s:295]
---------------------------- PROCESS ENDED (25339) for package cgeo.geocaching.developer ----------------------------

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

This was just starting c:geo and opening the map - no panning or zooming, but visible caches count > 200

Edit: to get an approx. cache count, II zommed so there were ~70, ~90, ~110, ~180, ~220 caches count - crash with the last one

@murggel
Copy link
Contributor Author

murggel commented May 13, 2024

@moving-bits anything I should try / can configure to get more information?

@MagpieFourtyTwo
Copy link

Already using log-properties.txt?
If not, see here for a complete description.
In short: In c:geo's log folder create a file called log-properties.txt with the following content:

logging.minlevel=VERBOSE
logging.mincallerinfolevel=VERBOSE
logging.throwonerror=false
logging.callerinfomaxdepth=10
logging.logfile=support

@MagpieFourtyTwo
Copy link

Nothing new over here? Unified Map is still completely unusable with OSM offline, lags like hell and crashes c:geo on the smallest moves in the map. And this not only with a time-honored Huawei P30Pro (which BTW is still top) with Android 10, but with a completely new Honor Magic6 Pro and Android 14 as well (which in fact behaves even worse WRT this issue).

Please let us know if we can help in any way,

@murggel
Copy link
Contributor Author

murggel commented May 19, 2024

Already using log-properties.txt?
If not, see here for a complete description.
In short: In c:geo's log folder create a file called log-properties.txt with the following content:

logging.minlevel=VERBOSE
logging.mincallerinfolevel=VERBOSE
logging.throwonerror=false
logging.callerinfomaxdepth=10
logging.logfile=support

Thanks I will try that..

Nothing new over here?
...
Please let us know if we can help in any way,

I just didn't have time yet to investigate further. I hope I will get some time in the next days...

@moving-bits
Copy link
Member

Nothing new over here?

Activity has decreased lately, probably all of us being quite busy right now...

Unified Map is still completely unusable with OSM offline, lags like hell and crashes c:geo on the smallest moves in the map.

It seems to depend on map and device being used. On my two devices (one with 2024.04.25 release, one with selected dev builds as needed), UnifiedMap is quite stable and performance is ok (with the number of caches I normally have on a map, that is). A few quirks happen from time to time, but also not really reproducible for me, which makes it hard to pursue the bugs.

For the "individual route" issues I have an idea what to try next, but haven't found the time yet to do so, but for the rest of the more annoying bugs I'm currently at loss how to investigate further.

@murggel
Copy link
Contributor Author

murggel commented May 19, 2024

@moving-bits I can reproduce on my device, running from Android-studio, so I can debug and try to add logs if I found some points of interest...

@MagpieFourtyTwo
Copy link

Another observation I made today: UM crashes immediately, when you perform any action (e. g. panning) while the refresh marker (the pulsing line between map and header) still indicates activity. I didn't notice this so clearly before, because under Android 10 this line is way darker and much narrower, in fact almost invisible. But with Android 14, it's almost "intrusive", so I've only now really noticed both, the line and the correlation between line and crashes. Perhaps this helps ...

@moving-bits
Copy link
Member

Another observation I made today: UM crashes immediately, when you perform any action (e. g. panning) while the refresh marker (the pulsing line between map and header) still indicates activity.

This is truly strange. I've just cross-checked with my two physical devices (Samsung, running on Android 14, and Motorola, running Android 13): Both seem to have no problems with panning while still loading caches, both in live map activated as in offline mode.

Can you post a logcat (or send to support, whatever you prefer)?

@murggel
Copy link
Contributor Author

murggel commented May 21, 2024

I got some time today to debug a little bit...

It seems to be dependent on MapsforgeVtmGeoItemLayer::getMarkerSymbol
If using vtmSingleMarkerSymbol == true the crash doesn't happen, so I guess something with using texture atlas for marker symbols (commit 032f3e4)

I had one crash, where the TextureRegion region was null, but only one time...

While debugging (with breakpoints on) it seems to me, that it crashes less often - so probably a timing / memory related problem?

I have often following entries in the log, but even if vtmSingleMarkerSymbol == true, so probably not related / dependent to the crash:
Choreographer cgeo.geocaching.developer I Skipped 39 frames! The application may be doing too much work on its main thread.
cgeo cgeo.geocaching.developer W [main] LooperLogger: long process time for >>>>> Dispatching to Handler (android.os.Handler)
ching.develope cgeo.geocaching.developer I NativeAlloc concurrent copying GC freed
ching.develope cgeo.geocaching.developer I Explicit concurrent copying GC freed

Didn't check with the different modes for compact-icon-mode yet ...

The last logs are whether
cgeo cgeo.geocaching.developer D [main] GeoItemLayer:clickableItems(MapsforgeVtmGeoItemLayer):ADD
or related to database:
DataStore.saveCaches
_AL setAlcMode: 0 {Geocache.setAlcMode:1296/DataStore.createCacheFromDatabaseContent:3217/DataStore.loadCachesFromGeocodes

I will continue this evening or tomorrow...

Any ideas where to put additional logs are appreciated ;-)

@eddiemuc
Copy link
Contributor

It seems to be dependent on MapsforgeVtmGeoItemLayer::getMarkerSymbol If using vtmSingleMarkerSymbol == true the crash doesn't happen, so I guess something with using texture atlas for marker symbols (commit 032f3e4)

I had one crash, where the TextureRegion region was null, but only one time...
...
Any ideas where to put additional logs are appreciated ;-)

PR #15742 adds a catching of exceptions around the Atlas-access-code of markersymbol. It also adds logging for exceptions in those cases. I put the PR against release since if this is the problematic area then release branch would also be affected.

@murggel
Copy link
Contributor Author

murggel commented May 22, 2024

I tried with PR #15742, still have the crash but unfortunately no exceptions related to Atlas-access-mode logged 🤔

logcat_pr_15742.txt

@eddiemuc
Copy link
Contributor

I tried with PR #15742, still have the crash but unfortunately no exceptions related to Atlas-access-mode logged 🤔

That's bad. If Atlas is really the issue, this would mean that it doesn't even throw an exception on problems. Let's hope the issue lies somewhere else....

@Lineflyer
Copy link
Member

FYI:
There is a log file sent by @murggel available in support ticket 629710.
I did not follow this thread and therefore not sure, whether this is still needed. I closed the support ticket but the information can be easily found via the ticket number.

@MagpieFourtyTwo
Copy link

MagpieFourtyTwo commented May 28, 2024

I did not follow this thread and therefore not sure, whether this is still needed.

Yes, it is, badly - UM is still completely unusable for us with all nightlies since 2024.05.12-NB-a1dd1ef.
It just needs a few actions in UM to crash c:geo, and these crashes even wreck other applications and widgets.

@Lineflyer
Copy link
Member

After scrolling through this issue I found, that I might also experience it on my tablet device.
Symptoms are:

  • At some early point the app crashes when trying to use the map
  • At first I was still able to pan or zoom
  • Now it crashes some seconds after starting the map even without interaction
  • Log shows nothing related

Anything I can do to help digging down?

@MagpieFourtyTwo
Copy link

MagpieFourtyTwo commented May 28, 2024

Which is in fact exactly what happens over here with

  • Huawei P30Pro, running Android 10
  • Honor Magic6 Pro, running Android 14

both usually used with offline OSM from OpenAndroMaps.

What I BTW found interesting was @murggel's finding

It seems to be dependent on MapsforgeVtmGeoItemLayer::getMarkerSymbol
If using vtmSingleMarkerSymbol == true the crash doesn't happen,
so I guess something with using texture atlas for marker symbols (commit 032f3e4)

Any chance for mortal humans like me to switch this off, i. e. create a scenario or change a setting which results in a scenario where vtmSingleMarkerSymbol is set to TRUE (apart from completely disabling UM, of course ;) )? Although source uses Settings.enableVtmSingleMarkerSymbol I was not able to spot neither this nor pref_vtm_single_marker_symbol in the system settings, thus I guess it's a somewhere hard wired constant ...?

Not to permanently disable it, but because I'm curious if this is the hulk over here, too ...

@murggel
Copy link
Contributor Author

murggel commented May 28, 2024

Unfortunately I have currently not so much time to dig deeper into it - I guess it needs more places to catch an exception and see ehat happened - but I can probably provide a PR for switching using atlas on/off...

@eddiemuc
Copy link
Contributor

If using vtmSingleMarkerSymbol == true the crash doesn't happen,
so I guess something with using texture atlas for marker symbols (commit 032f3e4)

Any chance for mortal humans like me to switch this off, i. e. create a scenario or change a setting which results in a scenario where vtmSingleMarkerSymbol is set to TRUE (apart from completely disabling UM, of course ;) )? Although source uses Settings.enableVtmSingleMarkerSymbol I was not able to spot neither this nor pref_vtm_single_marker_symbol in the system settings, thus I guess it's a somewhere hard wired constant ...?

You can manuall create the setting using Settings -> System -> View settings, then switch to edit mode by tapping the pen symbol, then tapping the plus symbol to add a new setting. Name the setting vtmSingleMarkerSymbol, make it of type boolean and set it to true.

This will lead to VTM rendering EVERY symbol on the map the same! (not only cache markers but also e.g. the position marker) So it is clearly for testing purposes only.

@MagpieFourtyTwo
Copy link

Thx - worked. Although creating the variable was a somewhat confusing process: It was of course no problem to create the variable, even setting it's value seemed to work like a charm, but after the value was assigned, resp. the value dialog was closed, the variable simply vanished. Seemingly. It was not visible in the settings. So I created/set it about three times, IIRC, already believed that I probably don't have permission to change the configuration ... but after leaving and re-entering the settings list again, it was finally there. Strange.

However, learned two more things:

  1. UM really seems to be (more?) stable with vtmSingleMarkerSymbol set to TRUE, at least in my "static" tests.
    Will check "live" tomorrow on my way to work.
  2. now it appears to be pretty clear to me, why Google Map & Sat are not affected by the crashes: Although all cache icons were rendered equal (i. e. are displayed as white c:geo symbols) in OSM, in GMap and Sat the icons were still "original" - which I would take as an indication that GMap and GSat don't even use this routine at all. So it's no wonder that UM still works with GMap and Sat. ;)

BTW: After that I switched back to vtmSingleMarkerSymbol = FALSE to check the crash again, and was even able to move the map back and forth a bit - but only until some more caches came into view (~100), then c:geo suddenly hung until it finally crashed. And this time it took at least "Business Calendar" with it: It's widget was cleared and only filled up with appointments again about one or two seconds after c:geo's crash.

@eddiemuc
Copy link
Contributor

Due to @MagpieFourtyTwo and other's test results:
PR #15752 disables VTM atlas usage by default, but allows enabling it by manually setting/adding a boolean property vtmMarkerAtlasUsage with value true

@murggel
Copy link
Contributor Author

murggel commented May 29, 2024

Unfortunately I have still not seen any reasonable / relevant logs after crashing, while debugging it seems to me, that I have to move around a lot more before the crash occurs.
I tried to add try/catch around the AsynchMapWrapper - nothing.

In logcat there are logs like
concurrent copying GC freed 167458(6459KB) AllocSpace objects, 1(192KB) LOS objects, 50% free, 19MB/38MB, ...
probably some memory issue?
Also the access to the database is quite frequently - probably some concurrent issue there?

@MagpieFourtyTwo
Copy link

MagpieFourtyTwo commented May 29, 2024

Unfortunately I have still not seen any reasonable / relevant logs after crashing,

As it's always not only c:geo that crashes, but even Bluetooth and WiFi connections drop, some background apps get killed (like Tasker and MyPhoneExplorer client) plus widgets get cleared, I could imagine that even fflush does not succeed, either.

while debugging it seems to me, that I have to move around a lot more before the crash occurs.

Perhaps it's a runtime/sync/memory problem, which takes longer to occur if c:geo gets some more time to breath.

However, I think I nailed a scenario where c:geo crashes pretty reproducible, even on more performant hardware with more RAM: Just visit a "cache polluted area" ;) zoom out until >500 caches are in view and then try to pan and zoom ...

While behavior differs a bit depending on used device:

  • Huawei P30 Pro (Android 10, 8 GB RAM, CPU 2x2,6 GHz Kirin 980):
    • as soon as too many (>100) caches appear, c:geo immediately crashes and restarts
  • Honor Magic6 Pro (Android 14, 12 GB RAM, Snapdragon 8 Gen 3, Octa Core (1x3,3 + 3x3,2 + 2x3,0 GHz + 2x2,3 GHz)):
    • with >500 caches in view update bar still shows activity for a few seconds (but way longer than to be expected)
    • after that it even seems to have finished updating (activity bar is inactive), but ...
    • map does not respond anymore
    • after another few seconds c:geo crashes

In both cases other apps are affected, too, even Bluetooth and WiFi connections drop for about half a second, as said before.

@eddiemuc
Copy link
Contributor

I think we seriously have to consider moving away from VTM and "back to mapsforge". Progress for map rotation looks promising imho, see eg #15743

@fm-sys
Copy link
Member

fm-sys commented May 29, 2024

Agreed. Or if it's not too much effort in the long run, provide "experimental" VMT and mapsforge in parallel, with mapsforge being the default. Adding mapsforge into unified map should actually be not too much effort IMHO.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Bug Issues classified as a bug Prio - High A significant malfunction of a feature/function. High user impact. Unverified Issue not yet confirmed/reproduced or feature requests not yet checked for plausibility
Projects
None yet
Development

No branches or pull requests

6 participants