I am running K-9 6.602 on a Pixel 4a with Android 13 and the latest security fixes. Periodically, K-9 hangs and will not download mail (using IMAP) even through I have a good Internet connection. If I try to refresh my mail, I get “Loading…” The only way to recover is to force stop K-9 My wife runs the same version on a Samsung Galaxy S8 (Android 9) and is not reporting this issue. I suspect the trigger is switching from WiFi to cell Internet, but the problem only occurs intermittently.
I had debug logging enabled - it is pretty short so I included it inline. Normally, the debug log is huge.
Any suggestions on how to track down the cause of this issue? I searched on some of the messages that suggested “Waiting for a blocking GC Alloc” related to memory allocation and found k9 Periodically gets stuck and refuses to check or send email · Issue #6538 · thunderbird/thunderbird-android · GitHub.
--------- beginning of main
09-26 14:25:24.703 12391 12407 I com.fsck.k9: Background young concurrent copying GC freed 105531(105MB) AllocSpace objects, 0(0B) LOS objects, 61% free, 44MB/115MB, paused 576us,163us total 171.207ms
09-26 14:25:25.004 12391 12407 I com.fsck.k9: Background concurrent copying GC freed 128885(128MB) AllocSpace objects, 0(0B) LOS objects, 73% free, 33MB/129MB, paused 455us,146us total 181.629ms
09-26 14:25:28.722 12391 12391 W OnBackInvokedCallback: OnBackInvokedCallback is not enabled for the application.
09-26 14:25:28.722 12391 12391 W OnBackInvokedCallback: Set ‘android:enableOnBackInvokedCallback=“true”’ in the application manifest.
09-26 14:25:28.812 12391 21086 E OpenGLRenderer: Unable to match the desired swap behavior.
09-26 14:25:30.098 12391 12391 W OnBackInvokedCallback: OnBackInvokedCallback is not enabled for the application.
09-26 14:25:30.098 12391 12391 W OnBackInvokedCallback: Set ‘android:enableOnBackInvokedCallback=“true”’ in the application manifest.
09-26 14:25:34.023 12391 12391 W OnBackInvokedCallback: OnBackInvokedCallback is not enabled for the application.
09-26 14:25:34.023 12391 12391 W OnBackInvokedCallback: Set ‘android:enableOnBackInvokedCallback=“true”’ in the application manifest.
09-26 14:25:40.962 12391 21086 W Parcel : Expecting binder but got null!
09-26 14:25:40.980 12391 21086 E OpenGLRenderer: Unable to match the desired swap behavior.
09-26 14:25:46.340 12391 21086 W Parcel : Expecting binder but got null!
09-26 14:25:46.355 12391 21086 E OpenGLRenderer: Unable to match the desired swap behavior.
09-26 14:25:53.664 12391 12391 W OnBackInvokedCallback: OnBackInvokedCallback is not enabled for the application.
09-26 14:25:53.664 12391 12391 W OnBackInvokedCallback: Set ‘android:enableOnBackInvokedCallback=“true”’ in the application manifest.
09-26 14:26:03.642 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:03.656 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 14.539ms
09-26 14:26:03.656 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:04.008 12391 21086 D OpenGLRenderer: endAllActiveAnimators on 0xb400006e6d3f5460 (FrameLayout) with handle 0xb400006d7d5ae4c0
09-26 14:26:05.057 12391 12407 I com.fsck.k9: Background young concurrent copying GC freed 102227(102MB) AllocSpace objects, 0(0B) LOS objects, 60% free, 45MB/115MB, paused 527us,283us total 206.588ms
09-26 14:26:05.318 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:05.379 12391 12407 I com.fsck.k9: Background concurrent copying GC freed 171770(138MB) AllocSpace objects, 0(0B) LOS objects, 75% free, 25MB/100MB, paused 874us,112us total 193.492ms
09-26 14:26:05.384 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 65.198ms
09-26 14:26:05.384 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:05.906 12391 12407 I com.fsck.k9: Background young concurrent copying GC freed 89205(89MB) AllocSpace objects, 0(0B) LOS objects, 57% free, 42MB/100MB, paused 97us,232us total 105.336ms
09-26 14:26:06.241 12391 12407 I com.fsck.k9: Background concurrent copying GC freed 140851(119MB) AllocSpace objects, 0(0B) LOS objects, 65% free, 50MB/146MB, paused 1.935ms,369us total 223.517ms
09-26 14:26:07.913 12391 12407 I com.fsck.k9: Background young concurrent copying GC freed 135705(135MB) AllocSpace objects, 0(0B) LOS objects, 78% free, 25MB/121MB, paused 3.446ms,520us total 112.165ms
09-26 14:26:13.009 12391 21086 E OpenGLRenderer: Unable to match the desired swap behavior.
09-26 14:26:13.136 12391 12407 I com.fsck.k9: Background young concurrent copying GC freed 109628(108MB) AllocSpace objects, 0(0B) LOS objects, 70% free, 34MB/118MB, paused 5.302ms,91us total 50.773ms
09-26 14:26:20.471 12391 21086 W Parcel : Expecting binder but got null!
09-26 14:26:20.484 12391 21086 E OpenGLRenderer: Unable to match the desired swap behavior.
09-26 14:26:21.038 12391 21086 D OpenGLRenderer: endAllActiveAnimators on 0xb400006e6d86c550 (FrameLayout) with handle 0xb400006d7d4a14e0
09-26 14:26:22.045 12391 12391 W OnBackInvokedCallback: OnBackInvokedCallback is not enabled for the application.
09-26 14:26:22.045 12391 12391 W OnBackInvokedCallback: Set ‘android:enableOnBackInvokedCallback=“true”’ in the application manifest.
09-26 14:26:25.887 12391 21086 W Parcel : Expecting binder but got null!
09-26 14:26:25.910 12391 21086 E OpenGLRenderer: Unable to match the desired swap behavior.
09-26 14:26:27.590 12391 21086 D OpenGLRenderer: endAllActiveAnimators on 0xb400006e6d869eb0 (MenuPopupWindow$MenuDropDownListView) with handle 0xb400006d7d5c32e0
09-26 14:26:28.624 12391 12407 I com.fsck.k9: Background young concurrent copying GC freed 107685(107MB) AllocSpace objects, 0(0B) LOS objects, 64% free, 42MB/118MB, paused 3.509ms,548us total 103.547ms
09-26 14:26:29.128 12391 12407 I com.fsck.k9: Background young concurrent copying GC freed 109271(109MB) AllocSpace objects, 0(0B) LOS objects, 63% free, 43MB/118MB, paused 2.764ms,121us total 117.432ms
09-26 14:26:57.992 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:58.008 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 15.974ms
09-26 14:26:58.008 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:58.430 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:58.444 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 13.620ms
09-26 14:26:58.444 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:58.569 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:58.585 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 16.024ms
09-26 14:26:58.585 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:58.704 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:58.719 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 14.707ms
09-26 14:26:58.719 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:59.213 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:59.230 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 17.432ms
09-26 14:26:59.230 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:59.359 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:59.367 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 7.720ms
09-26 14:26:59.367 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:59.484 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:59.495 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 10.985ms
09-26 14:26:59.495 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:59.612 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:59.626 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 13.934ms
09-26 14:26:59.626 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:59.741 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:59.754 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 12.206ms
09-26 14:26:59.754 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:26:59.868 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:26:59.882 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 14.034ms
09-26 14:26:59.882 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:27:00.014 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:27:00.021 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 7.760ms
09-26 14:27:00.021 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:27:00.145 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:27:00.164 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 18.858ms
09-26 14:27:00.164 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:27:00.538 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:27:00.563 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 25.561ms
09-26 14:27:00.563 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:27:00.675 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:27:00.705 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 29.483ms
09-26 14:27:00.705 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:27:00.821 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:27:00.839 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 18.320ms
09-26 14:27:00.839 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:27:00.951 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:27:00.978 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 27.310ms
09-26 14:27:00.978 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:27:01.234 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:27:01.245 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 11.678ms
09-26 14:27:01.245 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:27:01.494 12391 12441 I com.fsck.k9: Waiting for a blocking GC Alloc
09-26 14:27:01.503 12391 12441 I com.fsck.k9: WaitForGcToComplete blocked Alloc on Background for 8.539ms
09-26 14:27:01.503 12391 12441 I com.fsck.k9: Starting a blocking GC Alloc
09-26 14:27:01.560 12391 12391 D CompatibilityChangeReporter: Compat change id reported: 78294732; UID 10265; state: DISABLED
09-26 14:27:01.565 12391 12391 W OnBackInvokedCallback: OnBackInvokedCallback is not enabled for the application.
09-26 14:27:01.565 12391 12391 W OnBackInvokedCallback: Set ‘android:enableOnBackInvokedCallback=“true”’ in the application manifest.
09-26 14:27:01.576 12391 18034 V LogcatLogFileWriter: Writing logcat output to content URI: content://com.android.externalstorage.documents/document/primary%3ADownload%2Fk9mail-logs_20230926.txt
09-26 14:27:01.610 12391 21086 E OpenGLRenderer: Unable to match the desired swap behavior.