I denied an installation source change during an app update… and then the app closed
This isn't a new issue, but I finally got around to capturing a clean log.
It's a bit annoying because updating all apps will also reach this code path (consistently).
type: logcat
osVersion: google/akita/akita:17/CP2A.260705.006/2026071501:user/release-keys
flags: dev options enabled
package: com.looker.droidify:740, targetSdk 36
buffers: main,system,crash,events,kernel
level: verbose
--------- beginning of main
07-24 20:52:36.990 21327 21327 I libc : SetHeapTaggingLevel: tag level set to 0
07-24 20:52:36.994 21327 21327 I Zygote : Process 21327 created for com.looker.droidify
07-24 20:52:36.994 21327 21327 I looker.droidify: Using generational CollectorTypeCMC GC.
07-24 20:52:36.998 21327 21327 D nativeloader: Load libframework-connectivity-tiramisu-jni.so using APEX ns com_android_tethering for caller /apex/com.android.tethering/javalib/framework-connectivity-t.jar: ok
07-24 20:52:37.007 21327 21327 E FeatureFlagsImplExport: android.os.flagging.AconfigStorageReadException: ERROR_PACKAGE_NOT_FOUND: package android.xr cannot be found on the device
07-24 20:52:37.017 21327 21327 D DynCodeLoading: AppBindFlags: 0
07-24 20:52:37.017 21327 21327 I Typeface: Preloading /system/fonts/Roboto-Regular.ttf
07-24 20:52:37.017 21327 21327 I Typeface: Preloading /system/fonts/RobotoStatic-Regular.ttf
07-24 20:52:37.021 21327 21327 I SystemFonts: Loading font config from /system/etc/fonts.xml
07-24 20:52:37.043 21327 21327 D AssetManager: reusing systemIdmapPaths
07-24 20:52:37.058 21327 21327 D nativeloader: InitApexLibraries:
07-24 20:52:37.058 21327 21327 D nativeloader: com_android_art: libnativehelper.so
07-24 20:52:37.058 21327 21327 D nativeloader: com_android_i18n: libicui18n.so:libicuuc.so:libicu.so
07-24 20:52:37.058 21327 21327 D nativeloader: com_android_neuralnetworks: libneuralnetworks.so
07-24 20:52:37.058 21327 21327 D nativeloader: InitDefaultPublicLibraries for_preload=0: libandroid.so:libaaudio.so:libamidi.so:libbinder_ndk.so:libc.so:libcamera2ndk.so:libclang_rt.hwasan-aarch64-android.so:libdl.so:libEGL.so:libGLESv1_CM.so:libGLESv2.so:libGLESv3.so:libjnigraphics.so:liblog.so:libmediandk.so:libm.so:libnativewindow.so:libOpenMAXAL.so:libOpenSLES.so:libRS.so:libstdc++.so:libsync.so:libvulkan.so:libwebviewchromium_plat_support.so:libz.so
07-24 20:52:37.058 21327 21327 D nativeloader: Configuring clns-1 for other apk /system_ext/framework/androidx.window.extensions.jar. target_sdk_version=36, uses_libraries=ALL, library_path=/data/app/~~3CmG7z987UjHESpml_MbLQ==/com.looker.droidify-pkI-JMn9RB65j0btdLVlAw==/lib/arm64:/data/app/~~3CmG7z987UjHESpml_MbLQ==/com.looker.droidify-pkI-JMn9RB65j0btdLVlAw==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/com.looker.droidify
07-24 20:52:37.058 21327 21327 D nativeloader: InitExtendedPublicLibraries:
07-24 20:52:37.059 21327 21327 D nativeloader: InitApexLibraries:
07-24 20:52:37.059 21327 21327 D nativeloader:
07-24 20:52:37.059 21327 21327 D nativeloader: InitVendorPublicLibraries: libOpenCL.so:libOpenCL-pixel.so:libedgetpu_client.google.so:libedgetpu_util.so:lib_aion_buffer.so:lib_jpg_encoder.so:libgxp.so:libedgetpu_tachyon.google.so:libedgetpu_litert.so
07-24 20:52:37.059 21327 21327 D nativeloader: InitProductPublicLibraries:
07-24 20:52:37.061 21327 21327 D nativeloader: Configuring clns-2 for other apk /system_ext/framework/androidx.window.sidecar.jar. target_sdk_version=36, uses_libraries=ALL, library_path=/data/app/~~3CmG7z987UjHESpml_MbLQ==/com.looker.droidify-pkI-JMn9RB65j0btdLVlAw==/lib/arm64:/data/app/~~3CmG7z987UjHESpml_MbLQ==/com.looker.droidify-pkI-JMn9RB65j0btdLVlAw==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/com.looker.droidify
07-24 20:52:37.099 21327 21327 D nativeloader: Configuring clns-3 for other apk /data/app/~~3CmG7z987UjHESpml_MbLQ==/com.looker.droidify-pkI-JMn9RB65j0btdLVlAw==/base.apk. target_sdk_version=36, uses_libraries=, library_path=/data/app/~~3CmG7z987UjHESpml_MbLQ==/com.looker.droidify-pkI-JMn9RB65j0btdLVlAw==/lib/arm64:/data/app/~~3CmG7z987UjHESpml_MbLQ==/com.looker.droidify-pkI-JMn9RB65j0btdLVlAw==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/com.looker.droidify
07-24 20:52:37.118 21327 21327 D nativeloader: Load libframework-connectivity-jni.so using APEX ns com_android_tethering for caller /apex/com.android.tethering/javalib/framework-connectivity.jar: ok
07-24 20:52:37.121 21327 21327 V GraphicsEnvironment: Currently set values for:
07-24 20:52:37.121 21327 21327 V GraphicsEnvironment: angle_gl_driver_selection_pkgs=[com.android.angle, com.google.android.apps.tachyon]
07-24 20:52:37.121 21327 21327 V GraphicsEnvironment: angle_gl_driver_selection_values=[angle, native]
07-24 20:52:37.121 21327 21327 V GraphicsEnvironment: com.looker.droidify is not listed in per-application setting
07-24 20:52:37.121 21327 21327 V GraphicsEnvironment: No special selections for ANGLE, returning default driver choice
07-24 20:52:37.121 21327 21327 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported.
07-24 20:52:37.141 21327 21327 D ShizukuProvider: Initialize Sui: false
07-24 20:52:37.161 21327 21327 W System.err: SLF4J(W): No SLF4J providers were found.
07-24 20:52:37.161 21327 21327 W System.err: SLF4J(W): Defaulting to no-operation (NOP) logger implementation
07-24 20:52:37.161 21327 21327 W System.err: SLF4J(W): See https://www.slf4j.org/codes.html#noProviders for further details.
07-24 20:52:37.166 21327 21327 W libc : Access denied finding property "ro.debuggable"
--------- beginning of system
07-24 20:52:37.273 21327 21364 I DisplayManager: Choreographer implicitly registered for the refresh rate.
--------- switch to main
07-24 20:52:37.285 21327 21327 D DesktopExperienceFlags: Toggle override initialized to: false
07-24 20:52:37.319 21327 21327 D CompatChangeReporter: Compat change id reported: 377864165; UID 10482; state: ENABLED
--------- switch to system
07-24 20:52:37.327 21327 21327 I ImeTracker: com.looker.droidify:cced3ae4: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT fromUser false userId 0 displayId 0
07-24 20:52:37.329 21327 21327 I ImeTracker: com.looker.droidify:cced3ae4: onFailed at PHASE_CLIENT_VIEW_SERVED
--------- switch to main
07-24 20:52:37.329 21327 21327 D CompatChangeReporter: Compat change id reported: 395521150; UID 10482; state: ENABLED
--------- switch to system
07-24 20:52:37.329 21327 21327 I ImeTracker: com.looker.droidify:b0ee3b9: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT fromUser false userId 0 displayId 0
07-24 20:52:37.329 21327 21327 I ImeTracker: com.looker.droidify:b0ee3b9: onFailed at PHASE_CLIENT_VIEW_SERVED
--------- beginning of events
07-24 20:52:37.340 21327 21327 I wm_on_create_called: [Token=264318348,Component Name=com.looker.droidify.MainActivity,Reason=performCreate,time=44ms]
--------- switch to main
07-24 20:52:37.359 21327 21327 I HWUI : Using FreeType backend (prop=Auto)
--------- switch to events
07-24 20:52:37.372 21327 21327 I wm_on_start_called: [Token=264318348,Component Name=com.looker.droidify.MainActivity,Reason=handleStartActivity,time=31ms]
07-24 20:52:37.373 21327 21327 I wm_on_resume_called: [Token=264318348,Component Name=com.looker.droidify.MainActivity,Reason=RESUME_ACTIVITY,time=0ms]
07-24 20:52:37.390 21327 21327 I wm_on_top_resumed_gained_called: [Token=264318348,Component Name=com.looker.droidify.MainActivity,Reason=topStateChangedWhenResumed]
--------- switch to main
07-24 20:52:37.396 21327 21327 D WindowOnBackDispatcher: setTopOnBackInvokedCallback (unwrapped): android.app.Activity$$ExternalSyntheticLambda0@acaa969
07-24 20:52:37.396 21327 21327 D VRI[MainActivity]: WindowInsets changed: 1080x2400 statusBars:[0,121,0,0] navigationBars:[0,0,0,53] mandatorySystemGestures:[0,148,0,71]
07-24 20:52:37.405 21327 21327 I Surface : Creating surface for consumer unnamed-21327-0 with slotExpansion=1 for 64 slots
07-24 20:52:37.405 21327 21327 I Surface : Creating surface for consumer VRI[MainActivity]#0(BLAST Consumer)0 with slotExpansion=1 for 64 slots
--------- switch to events
07-24 20:52:37.405 21327 21327 I viewroot_draw_event: [window=VRI[MainActivity]fffae87,event=applyTransactionOnDraw applyImmediately]
--------- switch to main
07-24 20:52:37.406 21327 21327 D nativeloader: Load libstats_jni.so using APEX ns com_android_os_statsd for caller /apex/com.android.os.statsd/javalib/framework-statsd.jar: ok
--------- switch to events
07-24 20:52:37.452 21327 21327 I viewroot_draw_event: [window=VRI[MainActivity]fffae87,event=reportDrawFinished seqId=1]
07-24 20:52:37.486 21327 21327 I input_focus: window=ViewRootImpl focus=true for com.looker.droidify/com.looker.droidify.MainActivity
--------- switch to system
07-24 20:52:37.489 21327 21327 D InsetsController: hide(ime())
07-24 20:52:37.489 21327 21327 I ImeTracker: com.looker.droidify:21c2b92e: onCancelled at PHASE_CLIENT_ALREADY_HIDDEN
--------- switch to main
07-24 20:52:39.425 21327 21327 D WindowOnBackDispatcher: setTopOnBackInvokedCallback (unwrapped): androidx.navigationevent.OnBackInvokedInput$createOnBackAnimationCallback$1@3b6fb6e
07-24 20:52:39.426 21327 21327 D Bench : 3
--------- switch to system
07-24 20:52:39.431 21327 21327 I ImeTracker: com.looker.droidify:3978e3ac: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT fromUser false userId 0 displayId 0
07-24 20:52:39.431 21327 21327 D InsetsController: hide(ime())
07-24 20:52:39.431 21327 21327 I ImeTracker: com.looker.droidify:3978e3ac: onCancelled at PHASE_CLIENT_ALREADY_HIDDEN
--------- switch to main
07-24 20:52:39.451 21327 21327 W PropertyValuesHolder: Method set() with type float not found on target class class androidx.coordinatorlayout.widget.CoordinatorLayout
07-24 20:52:39.451 21327 21327 W PropertyValuesHolder: Method get() with type null not found on target class class androidx.coordinatorlayout.widget.CoordinatorLayout
07-24 20:52:39.451 21327 21327 W PropertyValuesHolder: Method setPercentTranslationY() with type float not found on target class class androidx.coordinatorlayout.widget.CoordinatorLayout
07-24 20:52:39.596 21327 21327 W View : requestLayout() improperly called by androidx.appcompat.widget.AppCompatTextView{75c36df V.ED..... ......ID 133,38-354,104} during layout: running second layout pass
07-24 20:52:39.596 21327 21327 W View : requestLayout() improperly called by androidx.appcompat.view.menu.ActionMenuItemView{4365ad5 VFED..C.. ......ID 0,9-107,116 #3} during layout: running second layout pass
07-24 20:52:39.596 21327 21327 W View : requestLayout() improperly called by androidx.appcompat.view.menu.ActionMenuItemView{8fc2ea VFED..C.. ......ID 107,9-214,116 #4} during layout: running second layout pass
07-24 20:52:39.596 21327 21327 W View : requestLayout() improperly called by androidx.appcompat.view.menu.ActionMenuItemView{958d868 VFED..C.. ......ID 214,9-321,116 #5} during layout: running second layout pass
07-24 20:52:39.596 21327 21327 W View : requestLayout() improperly called by androidx.appcompat.view.menu.ActionMenuItemView{5340481 VFED..C.. ......ID 321,9-428,116 #6} during layout: running second layout pass
07-24 20:52:39.596 21327 21327 W View : requestLayout() improperly called by androidx.appcompat.view.menu.ActionMenuItemView{d4b6926 VFED..C.. ......ID 428,9-535,116 #7} during layout: running second layout pass
07-24 20:52:39.596 21327 21327 W View : requestLayout() improperly called by androidx.appcompat.widget.ActionMenuView{e2ca3da V.E...... ......ID 438,8-1080,133} during layout: running second layout pass
07-24 20:52:40.738 21327 21327 I MaterialCardView: Setting a custom background is not supported.
--------- switch to system
07-24 20:52:41.517 21327 21327 I AconfigFlags: Using new flag storage
--------- switch to events
07-24 20:52:41.983 21327 21327 I wm_on_top_resumed_lost_called: [Token=264318348,Component Name=com.looker.droidify.MainActivity,Reason=topStateChangedWhenResumed]
07-24 20:52:41.984 21327 21327 I wm_on_paused_called: [Token=264318348,Component Name=com.looker.droidify.MainActivity,Reason=performPause,time=0ms]
07-24 20:52:42.612 21327 21327 I input_focus: window=ViewRootImpl focus=false for com.looker.droidify/com.looker.droidify.MainActivity
07-24 20:52:45.547 21327 21327 I wm_on_resume_called: [Token=264318348,Component Name=com.looker.droidify.MainActivity,Reason=RESUME_ACTIVITY,time=0ms]
07-24 20:52:45.547 21327 21327 I wm_on_top_resumed_gained_called: [Token=264318348,Component Name=com.looker.droidify.MainActivity,Reason=topStateChangedWhenResumed]
--------- switch to main
07-24 20:52:45.565 21327 21327 D AndroidRuntime: Shutting down VM
--------- beginning of crash
07-24 20:52:45.568 21327 21327 E AndroidRuntime: FATAL EXCEPTION: main
07-24 20:52:45.568 21327 21327 E AndroidRuntime: Process: com.looker.droidify, PID: 21327
07-24 20:52:45.568 21327 21327 E AndroidRuntime: java.lang.IllegalStateException: Already resumed, but proposed with update Failed
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at okhttp3.internal._UtilJvmKt$$ExternalSyntheticLambda0.m$1(r8-map-id-6f18872bd3fde0a185a537f1caa5fbea944431c8d7dce7b265584e9168710c09:32)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at kotlinx.coroutines.CancellableContinuationImpl.resumeImpl$kotlinx_coroutines_core(r8-map-id-6f18872bd3fde0a185a537f1caa5fbea944431c8d7dce7b265584e9168710c09:86)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at kotlinx.coroutines.CancellableContinuationImpl.resumeWith(r8-map-id-6f18872bd3fde0a185a537f1caa5fbea944431c8d7dce7b265584e9168710c09:17)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at com.looker.droidify.installer.installers.session.SessionInstaller$install$2$installerCallback$1.onFinished(r8-map-id-6f18872bd3fde0a185a537f1caa5fbea944431c8d7dce7b265584e9168710c09:14)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at android.content.pm.PackageInstaller$SessionCallbackDelegate$$ExternalSyntheticLambda3.accept(D8$$SyntheticClass:0)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at com.android.internal.util.function.pooled.PooledLambdaImpl.doInvoke(PooledLambdaImpl.java:295)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at com.android.internal.util.function.pooled.PooledLambdaImpl.invoke(PooledLambdaImpl.java:204)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at com.android.internal.util.function.pooled.OmniFunction.run(OmniFunction.java:87)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at android.os.Handler.handleCallback(Handler.java:1095)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at android.os.Handler.dispatchMessageImpl(Handler.java:135)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at android.os.Handler.dispatchMessage(Handler.java:125)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at android.os.Looper.loopOnce(Looper.java:296)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at android.os.Looper.loop(Looper.java:397)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at android.app.ActivityThread.main(ActivityThread.java:9571)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at java.lang.reflect.Method.invoke(Native Method)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:575)
07-24 20:52:45.568 21327 21327 E AndroidRuntime: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:982)
--------- switch to main
07-24 20:52:45.603 21327 21327 I Process : Sending signal. PID: 21327 SIG: 9
Before you submit
What happened?
I denied an installation source change during an app update… and then the app closed
How do we trigger it?
Your phone
Pixel8a
Android version
Android 17 (GrapheneOS)
Droid-ify version
v0.7.4
Anything else?
This isn't a new issue, but I finally got around to capturing a clean log.
It's a bit annoying because updating all apps will also reach this code path (consistently).
I hope this is enough to isolate the branch without having to debug it directly: