java.security.ProviderException: Failed to construct key object from newly generated key pair.
fail to generating attestation on Pixel 9 Pro XL logs from phone:
type: logcat
osVersion: google/komodo/komodo:15/AP4A.250105.002/2025011500:user/release-keys
package: app.attestation.auditor:87, targetSdk 35
buffers: main,system,crash,events,kernel
level: verbose
--------- beginning of main
01-25 04:14:32.366 4601 4601 I station.auditor: Using CollectorTypeCMC GC.
01-25 04:14:32.368 4601 4601 E station.auditor: Not starting debugger since process cannot load the jdwp agent.
01-25 04:14:32.414 4601 4601 W libc : Access denied finding property "ro.debuggable"
--------- beginning of events
01-25 04:14:32.412 4601 4601 I auditd : avc=type=1400 audit(0.0:706): avc: denied { read } for comm="app_process64" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.412 4601 4601 W app_process64: type=1400 audit(0.0:706): avc: denied { read } for name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
01-25 04:14:32.426 4601 4601 W libc : Access denied finding property "ro.debuggable"
01-25 04:14:32.426 4601 4601 W libc : Access denied finding property "ro.debuggable"
01-25 04:14:32.426 4601 4601 W libc : Access denied finding property "ro.debuggable"
01-25 04:14:32.427 4601 4601 D AndroidRuntime: >>>>>> START com.android.internal.os.RuntimeInit uid 10105 <<<<<<
01-25 04:14:32.427 4601 4601 W libc : Access denied finding property "ro.debuggable"
--------- switch to events
01-25 04:14:32.424 4601 4601 I auditd : avc=type=1400 audit(0.0:707): avc: denied { read } for comm="app_process64" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.424 4601 4601 W app_process64: type=1400 audit(0.0:707): avc: denied { read } for name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to events
01-25 04:14:32.424 4601 4601 I auditd : avc=type=1400 audit(0.0:708): avc: denied { read } for comm="app_process64" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.424 4601 4601 W app_process64: type=1400 audit(0.0:708): avc: denied { read } for name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to events
01-25 04:14:32.424 4601 4601 I auditd : avc=type=1400 audit(0.0:709): avc: denied { read } for comm="app_process64" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.424 4601 4601 W app_process64: type=1400 audit(0.0:709): avc: denied { read } for name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to events
01-25 04:14:32.424 4601 4601 I auditd : avc=type=1400 audit(0.0:710): avc: denied { read } for comm="station.auditor" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.424 4601 4601 W station.auditor: type=1400 audit(0.0:710): avc: denied { read } for name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
01-25 04:14:32.430 4601 4601 I AndroidRuntime: Using default boot image
01-25 04:14:32.430 4601 4601 I AndroidRuntime: Leaving lock profiling enabled
01-25 04:14:32.430 4601 4601 W libc : Access denied finding property "odsign.verification.success"
--------- switch to events
01-25 04:14:32.428 4601 4601 I auditd : avc=type=1400 audit(0.0:711): avc: denied { read } for comm="station.auditor" name="u:object_r:odsign_prop:s0" dev="tmpfs" ino=290 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:odsign_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.428 4601 4601 W station.auditor: type=1400 audit(0.0:711): avc: denied { read } for name="u:object_r:odsign_prop:s0" dev="tmpfs" ino=290 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:odsign_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
01-25 04:14:32.430 4601 4601 W libc : Access denied finding property "ro.debuggable"
--------- switch to events
01-25 04:14:32.428 4601 4601 I auditd : avc=type=1400 audit(0.0:712): avc: denied { read } for comm="station.auditor" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.428 4601 4601 W station.auditor: type=1400 audit(0.0:712): avc: denied { read } for name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
01-25 04:14:32.430 4601 4601 E cutils-trace: Error opening trace file: No such file or directory (2)
01-25 04:14:32.432 4601 4601 W app.attestation.auditor: ART APEX data files are untrusted.
01-25 04:14:32.432 4601 4601 I app.attestation.auditor: Using CollectorTypeCMC GC.
--------- switch to events
01-25 04:14:32.428 4601 4601 I auditd : avc=type=1400 audit(0.0:713): avc: denied { getattr } for comm="station.auditor" path="/apex/apex-info-list.xml" dev="tmpfs" ino=89 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:apex_info_file:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.428 4601 4601 W station.auditor: type=1400 audit(0.0:713): avc: denied { getattr } for path="/apex/apex-info-list.xml" dev="tmpfs" ino=89 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:apex_info_file:s0 tclass=file permissive=0 app=app.attestation.auditor
01-25 04:14:32.478 4601 4601 D nativeloader: InitDefaultPublicLibraries for_preload=1: libandroid.so:libaaudio.so:libamidi.so:libbinder_ndk.so:libc.so:libcamera2ndk.so:libdl.so:libEGL.so:libGLESv1_CM.so:libGLESv2.so:libGLESv3.so:libicu.so:libicui18n.so:libicuuc.so:libjnigraphics.so:liblog.so:libmediandk.so:libm.so:libnativehelper.so:libnativewindow.so:libOpenMAXAL.so:libOpenSLES.so:libRS.so:libstdc++.so:libsync.so:libvulkan.so:libwebviewchromium_plat_support.so:libz.so
01-25 04:14:32.486 4601 4601 D nativeloader: Load libicu_jni.so using APEX ns com_android_art for caller /apex/com.android.art/javalib/core-oj.jar: ok
01-25 04:14:32.486 4601 4601 D app.attestation.auditor: u_setTimeZoneFilesDirectory("/apex/com.android.tzdata/etc/tz/versioned/8/icu") succeeded.
01-25 04:14:32.486 4601 4601 D app.attestation.auditor: I18n APEX ICU file found: /apex/com.android.i18n/etc/icu/icudt75l.dat
01-25 04:14:32.486 4601 4601 D nativeloader: Load libjavacore.so using APEX ns com_android_art for caller /apex/com.android.art/javalib/core-oj.jar: ok
01-25 04:14:32.487 4601 4601 D nativeloader: Load libopenjdk.so using APEX ns com_android_art for caller /apex/com.android.art/javalib/core-oj.jar: ok
01-25 04:14:32.498 4601 4601 W libc : Access denied finding property "ro.product.name_for_attestation"
01-25 04:14:32.498 4601 4601 W libc : Access denied finding property "ro.product.device_for_attestation"
01-25 04:14:32.498 4601 4601 W libc : Access denied finding property "ro.product.manufacturer_for_attestation"
01-25 04:14:32.498 4601 4601 W libc : Access denied finding property "ro.product.brand_for_attestation"
01-25 04:14:32.498 4601 4601 W libc : Access denied finding property "ro.product.model_for_attestation"
--------- switch to events
01-25 04:14:32.496 4601 4601 I auditd : avc=type=1400 audit(0.0:714): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=112 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.496 4601 4601 W main : type=1400 audit(0.0:714): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=112 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to events
01-25 04:14:32.496 4601 4601 I auditd : avc=type=1400 audit(0.0:715): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=112 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.496 4601 4601 W main : type=1400 audit(0.0:715): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=112 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to events
01-25 04:14:32.496 4601 4601 I auditd : avc=type=1400 audit(0.0:716): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=112 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.496 4601 4601 W main : type=1400 audit(0.0:716): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=112 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to events
01-25 04:14:32.496 4601 4601 I auditd : avc=type=1400 audit(0.0:717): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=112 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.496 4601 4601 W main : type=1400 audit(0.0:717): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=112 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to events
01-25 04:14:32.496 4601 4601 I auditd : avc=type=1400 audit(0.0:718): avc: denied { read } for comm="main" name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=112 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.496 4601 4601 W main : type=1400 audit(0.0:718): avc: denied { read } for name="u:object_r:build_attestation_prop:s0" dev="tmpfs" ino=112 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:build_attestation_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
01-25 04:14:32.498 4601 4601 W libc : Access denied finding property "ro.debuggable"
--------- switch to events
01-25 04:14:32.496 4601 4601 I auditd : avc=type=1400 audit(0.0:719): avc: denied { read } for comm="main" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.496 4601 4601 W main : type=1400 audit(0.0:719): avc: denied { read } for name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
01-25 04:14:32.504 4601 4601 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
01-25 04:14:32.504 4601 4601 D AndroidRuntime: Calling main entry com.android.internal.os.ExecInit
01-25 04:14:32.504 4601 4601 D Zygote : begin preload
01-25 04:14:32.504 4601 4601 I Zygote : Calling ZygoteHooks.beginPreload()
01-25 04:14:32.516 4601 4601 I Zygote : Preloading shared libraries...
01-25 04:14:32.517 4601 4601 D nativeloader: Load libandroid.so using system ns (caller=/system/framework/framework.jar!classes5.dex): ok
01-25 04:14:32.517 4601 4601 D nativeloader: Load libjnigraphics.so using system ns (caller=/system/framework/framework.jar!classes5.dex): ok
01-25 04:14:32.517 4601 4601 D nativeloader: Load libcompiler_rt.so using system ns (caller=/system/framework/framework.jar!classes5.dex): ok
01-25 04:14:32.517 4601 4601 E app.attestation.auditor: Unable to find pattern file or unable to map it for am
01-25 04:14:32.518 4601 4601 I Zygote : Called ZygoteHooks.endPreload()
01-25 04:14:32.519 4601 4601 D nativeloader: Load libjavacrypto.so using APEX ns com_android_conscrypt for caller /apex/com.android.conscrypt/javalib/conscrypt.jar: ok
01-25 04:14:32.522 4601 4601 I Zygote : Installed AndroidKeyStoreProvider in 4ms.
01-25 04:14:32.522 4601 4601 D Zygote : end preload
01-25 04:14:32.527 4601 4620 W libc : Access denied finding property "qemu.sf.lcd_density"
01-25 04:14:32.527 4601 4620 W libc : Access denied finding property "qemu.sf.lcd_density"
--------- switch to events
01-25 04:14:32.524 4601 4601 I auditd : avc=type=1400 audit(0.0:720): avc: denied { read } for comm="binder:4601_2" name="u:object_r:qemu_sf_lcd_density_prop:s0" dev="tmpfs" ino=314 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:qemu_sf_lcd_density_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.524 4601 4601 W binder:4601_2: type=1400 audit(0.0:720): avc: denied { read } for name="u:object_r:qemu_sf_lcd_density_prop:s0" dev="tmpfs" ino=314 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:qemu_sf_lcd_density_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to events
01-25 04:14:32.524 4601 4601 I auditd : avc=type=1400 audit(0.0:721): avc: denied { read } for comm="binder:4601_2" name="u:object_r:qemu_sf_lcd_density_prop:s0" dev="tmpfs" ino=314 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:qemu_sf_lcd_density_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.524 4601 4601 W binder:4601_2: type=1400 audit(0.0:721): avc: denied { read } for name="u:object_r:qemu_sf_lcd_density_prop:s0" dev="tmpfs" ino=314 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:qemu_sf_lcd_density_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
01-25 04:14:32.535 4601 4601 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
01-25 04:14:32.536 4601 4601 I Typeface: Preloading /system/fonts/Roboto-Regular.ttf
01-25 04:14:32.536 4601 4601 I Typeface: Preloading /system/fonts/RobotoStatic-Regular.ttf
01-25 04:14:32.541 4601 4601 I SystemFonts: Loading font config from /system/etc/fonts.xml
01-25 04:14:32.561 4601 4601 D nativeloader: InitApexLibraries:
01-25 04:14:32.561 4601 4601 D nativeloader: com_android_art: libnativehelper.so
01-25 04:14:32.561 4601 4601 D nativeloader: com_android_i18n: libicui18n.so:libicuuc.so:libicu.so
01-25 04:14:32.561 4601 4601 D nativeloader: com_android_neuralnetworks: libneuralnetworks.so
01-25 04:14:32.561 4601 4601 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
01-25 04:14:32.561 4601 4601 D nativeloader: Configuring clns-1 for other apk /system_ext/framework/androidx.window.extensions.jar. target_sdk_version=35, uses_libraries=ALL, library_path=/product/app/Auditor/lib/arm64:/product/app/Auditor/Auditor.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/app.attestation.auditor
01-25 04:14:32.561 4601 4601 D nativeloader: InitExtendedPublicLibraries: libedgetpu_dba.google.so
01-25 04:14:32.561 4601 4601 D nativeloader: Extending system_exposed_libraries: libedgetpu_dba.google.so
01-25 04:14:32.561 4601 4601 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
01-25 04:14:32.561 4601 4601 D nativeloader: InitProductPublicLibraries:
01-25 04:14:32.562 4601 4601 D nativeloader: Configuring clns-2 for other apk /system_ext/framework/androidx.window.sidecar.jar. target_sdk_version=35, uses_libraries=ALL, library_path=/product/app/Auditor/lib/arm64:/product/app/Auditor/Auditor.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/app.attestation.auditor
01-25 04:14:32.562 4601 4601 D nativeloader: Extending system_exposed_libraries: libedgetpu_dba.google.so
01-25 04:14:32.570 4601 4601 D nativeloader: InitLlndkLibrariesProduct: libEGL.so:libGLESv1_CM.so:libGLESv2.so:libGLESv3.so:libRS.so:libandroid_net.so:libapexsupport.so:libbinder_ndk.so:libc.so:libcgrouprc.so:libclang_rt.asan-aarch64-android.so:libclang_rt.hwasan-aarch64-android.so:libcom.android.tethering.connectivity_native.so:libdl.so:libft2.so:liblog.so:libm.so:libmediandk.so:libnativewindow.so:libneuralnetworks.so:libselinux.so:libsync.so:libvendorsupport.so:libvndksupport.so:libvulkan.so
01-25 04:14:32.570 4601 4601 D nativeloader: Configuring product-clns-3 for unbundled product apk /product/app/Auditor/Auditor.apk. target_sdk_version=35, uses_libraries=, library_path=/product/app/Auditor/lib/arm64:/product/app/Auditor/Auditor.apk!/lib/arm64-v8a:/product/lib64:/system/product/lib64, permitted_path=/data:/mnt/expand:/data/user/0/app.attestation.auditor:/product/lib64:/system/product/lib64
01-25 04:14:32.570 4601 4601 D nativeloader: InitVndkspLibrariesProduct: VNDK is deprecated with product
01-25 04:14:32.570 4601 4601 D CompatChangeReporter: Compat change id reported: 202956589; UID 10105; state: ENABLED
01-25 04:14:32.573 4601 4601 D nativeloader: Load libframework-connectivity-jni.so using APEX ns com_android_tethering for caller /apex/com.android.tethering/javalib/framework-connectivity.jar: ok
01-25 04:14:32.573 4601 4601 V GraphicsEnvironment: Currently set values for:
01-25 04:14:32.573 4601 4601 V GraphicsEnvironment: angle_gl_driver_selection_pkgs=[com.android.angle, com.linecorp.b612.android, com.campmobile.snow, com.google.android.apps.tachyon]
01-25 04:14:32.573 4601 4601 V GraphicsEnvironment: angle_gl_driver_selection_values=[angle, native, native, native]
01-25 04:14:32.573 4601 4601 V GraphicsEnvironment: app.attestation.auditor is not listed in per-application setting
01-25 04:14:32.573 4601 4601 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported.
01-25 04:14:32.577 4601 4601 D CompatChangeReporter: Compat change id reported: 279646685; UID 10105; state: ENABLED
01-25 04:14:32.586 4601 4601 D AppCompatDelegate: Checking for metadata for AppLocalesMetadataHolderService : Service not found
01-25 04:14:32.595 4601 4601 D CompatChangeReporter: Compat change id reported: 309578419; UID 10105; state: ENABLED
01-25 04:14:32.596 4601 4601 D DesktopModeFlagsUtil: Toggle override initialized to: OVERRIDE_UNSET
01-25 04:14:32.601 4601 4601 D CompatChangeReporter: Compat change id reported: 63938206; UID 10105; state: ENABLED
--------- switch to events
01-25 04:14:32.607 4601 4601 I wm_on_create_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=performCreate,time=15ms]
01-25 04:14:32.608 4601 4601 I wm_on_start_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=handleStartActivity,time=0ms]
--------- switch to main
01-25 04:14:32.609 4601 4601 W WindowOnBackDispatcher: OnBackInvokedCallback is not enabled for the application.
01-25 04:14:32.609 4601 4601 W WindowOnBackDispatcher: Set 'android:enableOnBackInvokedCallback="true"' in the application manifest.
--------- switch to events
01-25 04:14:32.611 4601 4601 I wm_on_resume_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=RESUME_ACTIVITY,time=1ms]
--------- switch to main
01-25 04:14:32.612 4601 4601 D CompatChangeReporter: Compat change id reported: 349153669; UID 10105; state: ENABLED
01-25 04:14:32.612 4601 4601 W libc : Access denied finding property "ro.debuggable"
--------- switch to events
01-25 04:14:32.608 4601 4601 I auditd : avc=type=1400 audit(0.0:722): avc: denied { read } for comm="station.auditor" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.608 4601 4601 W station.auditor: type=1400 audit(0.0:722): avc: denied { read } for name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to events
01-25 04:14:32.615 4601 4601 I wm_on_top_resumed_gained_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=topStateChangedWhenResumed]
01-25 04:14:32.651 4601 4601 I viewroot_draw_event: [window=VRI[AttestationActivity],event=reportDrawFinished seqId=0]
01-25 04:14:32.652 4637 4637 I auditd : avc=type=1400 audit(0.0:723): avc: denied { read } for comm="getprop" name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
--------- switch to main
01-25 04:14:32.652 4637 4637 W getprop : type=1400 audit(0.0:723): avc: denied { read } for name="u:object_r:userdebug_or_eng_prop:s0" dev="tmpfs" ino=385 scontext=u:r:untrusted_app:s0:c105,c256,c512,c768 tcontext=u:object_r:userdebug_or_eng_prop:s0 tclass=file permissive=0 app=app.attestation.auditor
01-25 04:14:32.656 4637 4637 W libc : Access denied finding property "ro.debuggable"
01-25 04:14:38.538 4601 4641 D ProfileInstaller: Installing profile for app.attestation.auditor
--------- switch to events
01-25 04:14:46.470 4601 4601 I wm_on_top_resumed_lost_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=topStateChangedWhenResumed]
01-25 04:14:46.471 4601 4601 I wm_on_paused_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=performPause,time=0ms]
01-25 04:14:47.709 4601 4601 I wm_on_activity_result_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=ACTIVITY_RESULT]
01-25 04:14:47.709 4601 4601 I wm_on_resume_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=RESUME_ACTIVITY,time=0ms]
01-25 04:14:47.709 4601 4601 I wm_on_top_resumed_gained_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=topWhenResuming]
01-25 04:14:47.709 4601 4601 I wm_on_top_resumed_lost_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=topStateChangedWhenResumed]
01-25 04:14:47.709 4601 4601 I wm_on_paused_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=performPause,time=0ms]
--------- switch to main
01-25 04:14:47.725 4601 4669 I CameraManagerGlobal: Connecting to camera service
01-25 04:14:47.725 4601 4601 D CamLifecycleController: CameraProvider is not ready.
01-25 04:14:47.725 4601 4601 D CameraController: Use cases not attached to camera.
01-25 04:14:47.725 4601 4601 D CamLifecycleController: CameraProvider is not ready.
01-25 04:14:47.725 4601 4601 D CameraController: Use cases not attached to camera.
--------- switch to events
01-25 04:14:47.725 4601 4601 I wm_on_create_called: [Token=192404910,Component Name=app.attestation.auditor.QRScannerActivity,Reason=performCreate,time=9ms]
01-25 04:14:47.726 4601 4601 I wm_on_start_called: [Token=192404910,Component Name=app.attestation.auditor.QRScannerActivity,Reason=handleStartActivity,time=0ms]
01-25 04:14:47.726 4601 4601 I wm_on_resume_called: [Token=192404910,Component Name=app.attestation.auditor.QRScannerActivity,Reason=RESUME_ACTIVITY,time=1ms]
01-25 04:14:47.729 4601 4601 I wm_on_top_resumed_gained_called: [Token=192404910,Component Name=app.attestation.auditor.QRScannerActivity,Reason=topStateChangedWhenResumed]
--------- switch to main
01-25 04:14:47.737 4601 4669 D nativeloader: Load libmedia_jni.so using system ns (caller=/system/framework/framework.jar!classes2.dex): ok
01-25 04:14:47.737 4601 4669 D MtpDeviceJNI: register_android_mtp_MtpDevice
01-25 04:14:47.739 4601 4669 D CameraRepository: Added camera: 0
01-25 04:14:47.740 4601 4669 I Camera2CameraInfo: Device Level: INFO_SUPPORTED_HARDWARE_LEVEL_FULL
01-25 04:14:47.741 4601 4669 D CameraRepository: Added camera: 1
01-25 04:14:47.741 4601 4669 I Camera2CameraInfo: Device Level: INFO_SUPPORTED_HARDWARE_LEVEL_FULL
01-25 04:14:47.741 4601 4669 D CameraValidator: Verifying camera lens facing on komodo, lensFacingInteger: null
--------- switch to events
01-25 04:14:47.747 4601 4601 I viewroot_draw_event: [window=VRI[QRScannerActivity],event=reportDrawFinished seqId=0]
--------- switch to main
01-25 04:14:47.748 4601 4601 D CameraController: PreviewView not attached to CameraController.
01-25 04:14:47.748 4601 4601 D CameraController: Use cases not attached to camera.
01-25 04:14:47.750 4601 4601 D CameraOrientationUtil: getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90
01-25 04:14:47.750 4601 4601 D CameraOrientationUtil: getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90
01-25 04:14:47.752 4601 4601 D DynamicRangeResolver: Resolved dynamic range for use case x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef45 to no compatible HDR dynamic ranges.
01-25 04:14:47.752 4601 4601 D DynamicRangeResolver: DynamicRange@c3b5d90{encoding=UNSPECIFIED, bitDepth=0}
01-25 04:14:47.752 4601 4601 D DynamicRangeResolver: ->
01-25 04:14:47.752 4601 4601 D DynamicRangeResolver: DynamicRange@bd6c053{encoding=SDR, bitDepth=8}
01-25 04:14:47.753 4601 4601 D CameraOrientationUtil: getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90
01-25 04:14:47.753 4601 4601 D DeferrableSurface: Surface created[total_surfaces=1, used_surfaces=0](J.r@1b06245}
01-25 04:14:47.753 4601 4601 D DeferrableSurface: Surface created[total_surfaces=2, used_surfaces=0](x.h0@779ddc1}
01-25 04:14:47.753 4601 4601 D DeferrableSurface: New surface in use[total_surfaces=2, used_surfaces=1](x.h0@779ddc1}
01-25 04:14:47.753 4601 4601 D DeferrableSurface: use count+1, useCount=1 x.h0@779ddc1
01-25 04:14:47.753 4601 4601 D CameraOrientationUtil: getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90
01-25 04:14:47.754 4601 4601 D CameraOrientationUtil: getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90
01-25 04:14:47.754 4601 4601 D CameraOrientationUtil: getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90
01-25 04:14:47.754 4601 4601 D DeferrableSurface: Surface created[total_surfaces=3, used_surfaces=1](x.h0@d73b5f2}
01-25 04:14:47.754 4601 4672 D Camera2CameraImpl: {Camera@8592d18[id=0]} Use case x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737 ACTIVE
01-25 04:14:47.755 4601 4672 D UseCaseAttachState: Active and attached use case: [] for camera: 0
01-25 04:14:47.755 4601 4672 D Camera2CameraImpl: {Camera@8592d18[id=0]} Use case x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566 ACTIVE
01-25 04:14:47.755 4601 4672 D UseCaseAttachState: Active and attached use case: [] for camera: 0
01-25 04:14:47.755 4601 4672 D Camera2CameraImpl: {Camera@8592d18[id=0]} Use cases [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] now ATTACHED
01-25 04:14:47.755 4601 4672 D UseCaseAttachState: All use case: [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] for camera: 0
01-25 04:14:47.755 4601 4672 D UseCaseAttachState: Active and attached use case: [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] for camera: 0
01-25 04:14:47.755 4601 4672 D Camera2CameraImpl: {Camera@8592d18[id=0]} Resetting Capture Session
01-25 04:14:47.755 4601 4601 D PreviewView: Surface requested by Preview.
01-25 04:14:47.755 4601 4672 D Camera2CameraImpl: {Camera@8592d18[id=0]} Releasing session in state INITIALIZED
01-25 04:14:47.756 4601 4672 D Camera2CameraImpl: {Camera@8592d18[id=0]} Attempting to force open the camera.
01-25 04:14:47.756 4601 4672 D CameraStateRegistry: tryOpenCamera(Camera@8592d18[id=0]) [Available Cameras: 1, Already Open: false (Previous state: null)] --> SUCCESS
01-25 04:14:47.756 4601 4672 D CameraStateRegistry: Recalculating open cameras:
01-25 04:14:47.756 4601 4672 D CameraStateRegistry: Camera State
01-25 04:14:47.756 4601 4672 D CameraStateRegistry: -------------------------------------------------------------------
01-25 04:14:47.756 4601 4672 D CameraStateRegistry: Camera@8592d18[id=0] OPENING
01-25 04:14:47.756 4601 4672 D CameraStateRegistry: Camera@3b115ad[id=1] UNKNOWN
01-25 04:14:47.756 4601 4672 D CameraStateRegistry: -------------------------------------------------------------------
01-25 04:14:47.756 4601 4672 D CameraStateRegistry: Open count: 1 (Max allowed: 1)
01-25 04:14:47.756 4601 4672 D Camera2CameraImpl: {Camera@8592d18[id=0]} Opening camera.
01-25 04:14:47.756 4601 4672 D Camera2CameraImpl: {Camera@8592d18[id=0]} Transitioning camera internal state: INITIALIZED --> OPENING
01-25 04:14:47.756 4601 4672 D CameraStateMachine: New public camera state CameraState{type=OPENING, error=null} from OPENING and null
01-25 04:14:47.756 4601 4672 D CameraStateMachine: Publishing new public camera state CameraState{type=OPENING, error=null}
01-25 04:14:47.756 4601 4672 D UseCaseAttachState: All use case: [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] for camera: 0
01-25 04:14:47.756 4601 4601 D PreviewView: Preview transformation info updated. TransformationInfo{getCropRect=Rect(0, 0 - 1600, 1200), getRotationDegrees=90, getTargetRotation=-1, hasCameraTransform=true, getSensorToBufferTransform=Matrix{[1.0, 0.0, 0.0][0.0, 1.0, 0.0][0.0, 0.0, 1.0]}, getMirroring=false}
01-25 04:14:47.756 4601 4601 D PreviewTransform: Transformation info set: TransformationInfo{getCropRect=Rect(0, 0 - 1600, 1200), getRotationDegrees=90, getTargetRotation=-1, hasCameraTransform=true, getSensorToBufferTransform=Matrix{[1.0, 0.0, 0.0][0.0, 1.0, 0.0][0.0, 0.0, 1.0]}, getMirroring=false} 1600x1200 false
01-25 04:14:47.756 4601 4601 D CameraOrientationUtil: getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90
--------- switch to events
01-25 04:14:47.760 4601 4601 I surfaceview_layout: [window=SV[237369366 QRScannerActivity],format=4,width=1600,height=1200,z=-2,sizeFrom=layout,attached=1,lifecycleStrategy=0,viewVisible=1]
01-25 04:14:47.761 4601 4601 I surfaceview_callback: [window=SV[237369366 QRScannerActivity],callback=surfaceCreated]
--------- switch to main
01-25 04:14:47.761 4601 4601 D SurfaceViewImpl: Surface created.
--------- switch to events
01-25 04:14:47.761 4601 4601 I surfaceview_callback: [window=SV[237369366 QRScannerActivity],callback=surfaceChanged -- format=4 w=1600 h=1200]
--------- switch to main
01-25 04:14:47.761 4601 4601 D SurfaceViewImpl: Surface changed. Size: 1600x1200
--------- switch to events
01-25 04:14:47.761 4601 4601 I surfaceview_callback: [window=SV[237369366 QRScannerActivity],callback=surfaceRedrawNeeded]
--------- switch to main
01-25 04:14:47.767 4601 4601 D SurfaceViewImpl: Surface set on Preview.
01-25 04:14:47.786 4601 4672 D Camera2CameraImpl: {Camera@8592d18[id=0]} Use case x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737 ACTIVE
01-25 04:14:47.786 4601 4672 D UseCaseAttachState: Active and attached use case: [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] for camera: 0
01-25 04:14:47.786 4601 4672 D Camera2CameraImpl: {Camera@8592d18[id=0]} Use case x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566 ACTIVE
01-25 04:14:47.786 4601 4672 D UseCaseAttachState: Active and attached use case: [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] for camera: 0
01-25 04:14:47.786 4601 4672 D UseCaseAttachState: Active and attached use case: [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] for camera: 0
01-25 04:14:47.787 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} CameraDevice.onOpened()
01-25 04:14:47.787 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Transitioning camera internal state: OPENING --> OPENED
01-25 04:14:47.787 4601 4669 D CameraStateRegistry: Recalculating open cameras:
01-25 04:14:47.787 4601 4669 D CameraStateRegistry: Camera State
01-25 04:14:47.787 4601 4669 D CameraStateRegistry: -------------------------------------------------------------------
01-25 04:14:47.787 4601 4669 D CameraStateRegistry: Camera@8592d18[id=0] OPEN
01-25 04:14:47.787 4601 4669 D CameraStateRegistry: Camera@3b115ad[id=1] UNKNOWN
01-25 04:14:47.787 4601 4669 D CameraStateRegistry: -------------------------------------------------------------------
01-25 04:14:47.787 4601 4669 D CameraStateRegistry: Open count: 1 (Max allowed: 1)
01-25 04:14:47.787 4601 4669 D CameraStateMachine: New public camera state CameraState{type=OPEN, error=null} from OPEN and null
01-25 04:14:47.787 4601 4669 D CameraStateMachine: Publishing new public camera state CameraState{type=OPEN, error=null}
01-25 04:14:47.787 4601 4669 D UseCaseAttachState: All use case: [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] for camera: 0
01-25 04:14:47.788 4601 4669 D SyncCaptureSessionBase: [q.h0@b6965b4] getSurface...done
01-25 04:14:47.788 4601 4669 D CaptureSession: Opening capture session.
01-25 04:14:47.789 4601 4669 D DeferrableSurface: use count+1, useCount=2 x.h0@779ddc1
01-25 04:14:47.789 4601 4669 D DeferrableSurface: New surface in use[total_surfaces=3, used_surfaces=2](x.h0@d73b5f2}
01-25 04:14:47.789 4601 4669 D DeferrableSurface: use count+1, useCount=1 x.h0@d73b5f2
01-25 04:14:47.821 4601 4669 D CaptureSession: Attempting to send capture request onConfigured
01-25 04:14:47.821 4601 4669 D CaptureSession: Issuing request for session.
01-25 04:14:47.821 4601 4669 D Camera2CaptureRequestBuilder: createCaptureRequest
01-25 04:14:47.824 4601 4669 D CaptureSession: CameraCaptureSession.onConfigured() mState=OPENED
01-25 04:14:47.824 4601 4669 D CaptureSession: CameraCaptureSession.onReady() OPENED
01-25 04:14:48.054 4601 4669 D StreamStateObserver: Update Preview stream state to STREAMING
01-25 04:14:48.262 4601 4601 D VRI[AttestationActivity]: visibilityChanged oldVisibility=true newVisibility=false
--------- switch to events
01-25 04:14:48.272 4601 4601 I viewroot_draw_event: [window=VRI[AttestationActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
01-25 04:14:48.272 4601 4601 I wm_on_stop_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=STOP_ACTIVITY_ITEM,time=0ms]
--------- switch to main
01-25 04:14:48.280 4601 4601 D CameraOrientationUtil: getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90
01-25 04:14:48.702 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 10.48
01-25 04:14:49.377 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 14.81
01-25 04:14:49.757 4601 4669 D UseCaseAttachState: Active and attached use case: [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] for camera: 0
01-25 04:14:49.757 4601 4669 D CaptureSession: Attempting to submit CaptureRequest after setting
01-25 04:14:49.758 4601 4669 D CaptureSession: Issuing request for session.
01-25 04:14:49.758 4601 4669 D Camera2CaptureRequestBuilder: createCaptureRequest
01-25 04:14:49.765 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Issue capture request
01-25 04:14:49.765 4601 4669 D CaptureSession: Issuing capture request.
01-25 04:14:49.767 4601 4669 D Camera2CaptureRequestBuilder: createCaptureRequest
01-25 04:14:50.046 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 14.95
01-25 04:14:50.724 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 14.75
01-25 04:14:51.384 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 15.16
01-25 04:14:51.756 4601 4669 D UseCaseAttachState: Active and attached use case: [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] for camera: 0
01-25 04:14:51.757 4601 4669 D CaptureSession: Attempting to submit CaptureRequest after setting
01-25 04:14:51.757 4601 4669 D CaptureSession: Issuing request for session.
01-25 04:14:51.757 4601 4669 D Camera2CaptureRequestBuilder: createCaptureRequest
01-25 04:14:51.760 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Issue capture request
01-25 04:14:51.760 4601 4669 D CaptureSession: Issuing capture request.
01-25 04:14:51.760 4601 4669 D Camera2CaptureRequestBuilder: createCaptureRequest
01-25 04:14:51.773 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 25.66
01-25 04:14:52.111 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 29.62
01-25 04:14:52.502 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 25.60
01-25 04:14:52.832 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 30.29
--------- switch to events
01-25 04:14:53.081 4601 4601 I wm_on_top_resumed_lost_called: [Token=192404910,Component Name=app.attestation.auditor.QRScannerActivity,Reason=topStateChangedWhenResumed]
01-25 04:14:53.082 4601 4601 I wm_on_paused_called: [Token=192404910,Component Name=app.attestation.auditor.QRScannerActivity,Reason=performPause,time=0ms]
--------- switch to main
01-25 04:14:53.095 4601 4601 D CameraOrientationUtil: getRelativeImageRotation: destRotationDegrees=0, sourceRotationDegrees=90, isOppositeFacing=true, result=90
--------- switch to events
01-25 04:14:53.107 4601 4601 I wm_on_restart_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=performRestart,time=0ms]
01-25 04:14:53.108 4601 4601 I wm_on_start_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=handleStartActivity,time=2ms]
--------- switch to main
01-25 04:14:53.109 4601 4601 W WindowOnBackDispatcher: OnBackInvokedCallback is not enabled for the application.
01-25 04:14:53.109 4601 4601 W WindowOnBackDispatcher: Set 'android:enableOnBackInvokedCallback="true"' in the application manifest.
01-25 04:14:53.110 4601 4601 D AttestationActivity: onActivityResult 665042776 -1
--------- switch to events
01-25 04:14:53.110 4601 4601 I wm_on_activity_result_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=ACTIVITY_RESULT]
01-25 04:14:53.110 4601 4601 I wm_on_resume_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=RESUME_ACTIVITY,time=0ms]
01-25 04:14:53.110 4601 4601 I wm_on_top_resumed_gained_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=topWhenResuming]
01-25 04:14:53.120 4601 4601 I viewroot_draw_event: [window=VRI[AttestationActivity],event=Start draw after previous draw not visible]
01-25 04:14:53.128 4601 4601 I viewroot_draw_event: [window=VRI[AttestationActivity],event=reportDrawFinished seqId=0]
--------- switch to main
01-25 04:14:53.166 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 29.92
01-25 04:14:53.505 4601 4738 D QRCodeImageAnalyzer: Analysis FPS: 29.51
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: Couldn't parse certificate in keystore
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: java.security.cert.CertificateException: com.android.org.conscrypt.OpenSSLX509CertificateFactory$ParsingException: com.android.org.conscrypt.OpenSSLX509CertificateFactory$ParsingException: java.lang.RuntimeException: error:0c00006d:ASN.1 encoding routines:OPENSSL_internal:DECODE_ERROR
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at com.android.org.conscrypt.OpenSSLX509CertificateFactory.engineGenerateCertificate(OpenSSLX509CertificateFactory.java:303)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at java.security.cert.CertificateFactory.generateCertificate(CertificateFactory.java:366)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at android.security.keystore2.AndroidKeyStoreSpi.toCertificate(AndroidKeyStoreSpi.java:240)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at android.security.keystore2.AndroidKeyStoreProvider.makeAndroidKeyStorePublicKeyFromKeyEntryResponse(AndroidKeyStoreProvider.java:231)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at android.security.keystore2.AndroidKeyStoreKeyPairGeneratorSpi.generateKeyPair(AndroidKeyStoreKeyPairGeneratorSpi.java:702)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at java.security.KeyPairGenerator$Delegate.generateKeyPair(KeyPairGenerator.java:750)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at T0.q.f(Unknown Source:11)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at T0.q.g(Unknown Source:213)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at T0.b.run(Unknown Source:19)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:487)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at java.util.concurrent.FutureTask.run(FutureTask.java:264)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:644)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at java.lang.Thread.run(Thread.java:1117)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: Caused by: com.android.org.conscrypt.OpenSSLX509CertificateFactory$ParsingException: com.android.org.conscrypt.OpenSSLX509CertificateFactory$ParsingException: java.lang.RuntimeException: error:0c00006d:ASN.1 encoding routines:OPENSSL_internal:DECODE_ERROR
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at com.android.org.conscrypt.OpenSSLX509CertificateFactory$Parser.generateItem(OpenSSLX509CertificateFactory.java:147)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at com.android.org.conscrypt.OpenSSLX509CertificateFactory.engineGenerateCertificate(OpenSSLX509CertificateFactory.java:301)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: ... 13 more
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: Caused by: com.android.org.conscrypt.OpenSSLX509CertificateFactory$ParsingException: java.lang.RuntimeException: error:0c00006d:ASN.1 encoding routines:OPENSSL_internal:DECODE_ERROR
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at com.android.org.conscrypt.OpenSSLX509Certificate.fromX509DerInputStream(OpenSSLX509Certificate.java:106)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at com.android.org.conscrypt.OpenSSLX509CertificateFactory$1.fromX509DerInputStream(OpenSSLX509CertificateFactory.java:253)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at com.android.org.conscrypt.OpenSSLX509CertificateFactory$1.fromX509DerInputStream(OpenSSLX509CertificateFactory.java:243)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at com.android.org.conscrypt.OpenSSLX509CertificateFactory$Parser.generateItem(OpenSSLX509CertificateFactory.java:137)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: ... 14 more
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: Caused by: java.lang.RuntimeException: error:0c00006d:ASN.1 encoding routines:OPENSSL_internal:DECODE_ERROR
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at com.android.org.conscrypt.NativeCrypto.d2i_X509_bio(Native Method)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: at com.android.org.conscrypt.OpenSSLX509Certificate.fromX509DerInputStream(OpenSSLX509Certificate.java:100)
01-25 04:14:53.588 4601 4753 W AndroidKeyStore: ... 17 more
01-25 04:14:53.591 4601 4753 E AttestationActivity: attestation generation error
01-25 04:14:53.591 4601 4753 E AttestationActivity: java.io.IOException: java.security.ProviderException: Failed to construct key object from newly generated key pair.
01-25 04:14:53.591 4601 4753 E AttestationActivity: at T0.q.f(Unknown Source:18)
01-25 04:14:53.591 4601 4753 E AttestationActivity: at T0.q.g(Unknown Source:213)
01-25 04:14:53.591 4601 4753 E AttestationActivity: at T0.b.run(Unknown Source:19)
01-25 04:14:53.591 4601 4753 E AttestationActivity: at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:487)
01-25 04:14:53.591 4601 4753 E AttestationActivity: at java.util.concurrent.FutureTask.run(FutureTask.java:264)
01-25 04:14:53.591 4601 4753 E AttestationActivity: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
01-25 04:14:53.591 4601 4753 E AttestationActivity: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:644)
01-25 04:14:53.591 4601 4753 E AttestationActivity: at java.lang.Thread.run(Thread.java:1117)
01-25 04:14:53.591 4601 4753 E AttestationActivity: Caused by: java.security.ProviderException: Failed to construct key object from newly generated key pair.
01-25 04:14:53.591 4601 4753 E AttestationActivity: at android.security.keystore2.AndroidKeyStoreKeyPairGeneratorSpi.generateKeyPair(AndroidKeyStoreKeyPairGeneratorSpi.java:719)
01-25 04:14:53.591 4601 4753 E AttestationActivity: at java.security.KeyPairGenerator$Delegate.generateKeyPair(KeyPairGenerator.java:750)
01-25 04:14:53.591 4601 4753 E AttestationActivity: at T0.q.f(Unknown Source:11)
01-25 04:14:53.591 4601 4753 E AttestationActivity: ... 7 more
01-25 04:14:53.591 4601 4753 E AttestationActivity: Caused by: java.security.UnrecoverableKeyException: Failed to parse the X.509 certificate containing the public key. This likely indicates a hardware problem.
01-25 04:14:53.591 4601 4753 E AttestationActivity: at android.security.keystore2.AndroidKeyStoreProvider.makeAndroidKeyStorePublicKeyFromKeyEntryResponse(AndroidKeyStoreProvider.java:233)
01-25 04:14:53.591 4601 4753 E AttestationActivity: at android.security.keystore2.AndroidKeyStoreKeyPairGeneratorSpi.generateKeyPair(AndroidKeyStoreKeyPairGeneratorSpi.java:702)
01-25 04:14:53.591 4601 4753 E AttestationActivity: ... 9 more
01-25 04:14:53.649 4601 4601 D VRI[QRScannerActivity]: visibilityChanged oldVisibility=true newVisibility=false
--------- switch to events
01-25 04:14:53.656 4601 4601 I surfaceview_layout: [window=SV[237369366 QRScannerActivity],format=4,width=1600,height=1200,z=-2,sizeFrom=layout,attached=1,lifecycleStrategy=0,viewVisible=0]
01-25 04:14:53.657 4601 4601 I surfaceview_callback: [window=SV[237369366 QRScannerActivity],callback=surfaceDestroyed]
--------- switch to main
01-25 04:14:53.657 4601 4601 D SurfaceViewImpl: Surface destroyed.
01-25 04:14:53.657 4601 4601 D SurfaceViewImpl: Surface closed x.k0@c954ea8
01-25 04:14:53.657 4601 4601 D DeferrableSurface: surface closed, useCount=2 closed=true x.h0@779ddc1
01-25 04:14:53.661 4601 4686 E BufferQueueProducer: [SurfaceView[app.attestation.auditor/app.attestation.auditor.QRScannerActivity]#2(BLAST Consumer)2](id:11f900000004,api:4,p:953,c:4601) queueBuffer: BufferQueue has been abandoned
--------- switch to events
01-25 04:14:53.663 4601 4601 I viewroot_draw_event: [window=VRI[QRScannerActivity],event=applyTransactionOnDraw applyImmediately]
01-25 04:14:53.663 4601 4601 I viewroot_draw_event: [window=VRI[QRScannerActivity],event=applyTransactionOnDraw applyImmediately]
01-25 04:14:53.663 4601 4601 I viewroot_draw_event: [window=VRI[QRScannerActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
--------- switch to main
01-25 04:14:53.664 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Use cases [x.b0-8e41d9f5-0b4a-4e2c-a617-937cdc14ef4577446737, x.D-c9741d5c-4e30-48a8-ac0f-d99a43b7be2d181200566] now DETACHED for camera
01-25 04:14:53.664 4601 4669 D UseCaseAttachState: All use case: [] for camera: 0
01-25 04:14:53.665 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Resetting Capture Session
--------- switch to events
01-25 04:14:53.665 4601 4601 I wm_on_stop_called: [Token=192404910,Component Name=app.attestation.auditor.QRScannerActivity,Reason=LIFECYCLER_STOP_ACTIVITY,time=0ms]
--------- switch to main
01-25 04:14:53.665 4601 4601 D DeferrableSurface: surface closed, useCount=0 closed=true J.r@1b06245
01-25 04:14:53.665 4601 4601 D DeferrableSurface: Surface terminated[total_surfaces=2, used_surfaces=2](J.r@1b06245}
01-25 04:14:53.665 4601 4601 D DeferrableSurface: use count-1, useCount=1 closed=true x.h0@779ddc1
01-25 04:14:53.665 4601 4601 D DeferrableSurface: surface closed, useCount=1 closed=true x.h0@d73b5f2
--------- switch to events
01-25 04:14:53.665 4601 4601 I wm_on_destroy_called: [Token=192404910,Component Name=app.attestation.auditor.QRScannerActivity,Reason=performDestroy,time=0ms]
--------- switch to main
01-25 04:14:53.666 4601 4601 W WindowOnBackDispatcher: sendCancelIfRunning: isInProgress=false callback=android.view.ViewRootImpl$$ExternalSyntheticLambda11@c18fc83
--------- switch to events
01-25 04:14:53.666 4601 4601 I viewroot_draw_event: [window=VRI[QRScannerActivity],event=applyTransactionOnDraw applyImmediately]
01-25 04:14:53.666 4601 4601 I viewroot_draw_event: [window=VRI[QRScannerActivity],event=applyTransactionOnDraw applyImmediately]
--------- switch to main
01-25 04:14:53.667 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Releasing session in state OPENED
01-25 04:14:53.668 4601 4669 D UseCaseAttachState: Active and attached use case: [] for camera: 0
01-25 04:14:53.669 4601 4669 D UseCaseAttachState: Active and attached use case: [] for camera: 0
01-25 04:14:53.669 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Closing camera.
01-25 04:14:53.669 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Transitioning camera internal state: OPENED --> CLOSING
01-25 04:14:53.669 4601 4669 D CameraStateRegistry: Recalculating open cameras:
01-25 04:14:53.669 4601 4669 D CameraStateRegistry: Camera State
01-25 04:14:53.669 4601 4669 D CameraStateRegistry: -------------------------------------------------------------------
01-25 04:14:53.669 4601 4669 D CameraStateRegistry: Camera@8592d18[id=0] CLOSING
01-25 04:14:53.669 4601 4669 D CameraStateRegistry: Camera@3b115ad[id=1] UNKNOWN
01-25 04:14:53.669 4601 4669 D CameraStateRegistry: -------------------------------------------------------------------
01-25 04:14:53.669 4601 4669 D CameraStateRegistry: Open count: 1 (Max allowed: 1)
01-25 04:14:53.669 4601 4669 D CameraStateMachine: New public camera state CameraState{type=CLOSING, error=null} from CLOSING and null
01-25 04:14:53.669 4601 4669 D CameraStateMachine: Publishing new public camera state CameraState{type=CLOSING, error=null}
01-25 04:14:53.669 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Resetting Capture Session
01-25 04:14:53.669 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Releasing session in state CLOSING
01-25 04:14:53.669 4601 4669 D CaptureSession: onSessionFinished()
01-25 04:14:53.672 4601 4601 D StreamStateObserver: Update Preview stream state to IDLE
01-25 04:14:53.680 4601 4686 E BufferQueueProducer: [SurfaceView[app.attestation.auditor/app.attestation.auditor.QRScannerActivity]#2(BLAST Consumer)2](id:11f900000004,api:4,p:953,c:4601) dequeueBuffer: BufferQueue has been abandoned
01-25 04:14:53.692 4601 4686 E BufferQueueProducer: [SurfaceView[app.attestation.auditor/app.attestation.auditor.QRScannerActivity]#2(BLAST Consumer)2](id:11f900000004,api:4,p:953,c:4601) queueBuffer: BufferQueue has been abandoned
01-25 04:14:53.930 4601 4669 D UseCaseAttachState: Active and attached use case: [] for camera: 0
01-25 04:14:53.931 4601 4669 D DeferrableSurface: use count-1, useCount=0 closed=true x.h0@779ddc1
01-25 04:14:53.931 4601 4669 D DeferrableSurface: Surface no longer in use[total_surfaces=2, used_surfaces=1](x.h0@779ddc1}
01-25 04:14:53.931 4601 4669 D DeferrableSurface: Surface terminated[total_surfaces=1, used_surfaces=1](x.h0@779ddc1}
01-25 04:14:53.931 4601 4669 D DeferrableSurface: use count-1, useCount=0 closed=true x.h0@d73b5f2
01-25 04:14:53.931 4601 4669 D DeferrableSurface: Surface no longer in use[total_surfaces=1, used_surfaces=0](x.h0@d73b5f2}
01-25 04:14:53.931 4601 4669 D DeferrableSurface: Surface terminated[total_surfaces=0, used_surfaces=0](x.h0@d73b5f2}
01-25 04:14:53.931 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} CameraDevice.onClosed()
01-25 04:14:53.931 4601 4669 D Camera2CameraImpl: {Camera@8592d18[id=0]} Transitioning camera internal state: CLOSING --> INITIALIZED
01-25 04:14:53.934 4601 4601 D SurfaceViewImpl: Safe to release surface.
01-25 04:14:53.934 4601 4669 D CameraStateRegistry: Recalculating open cameras:
01-25 04:14:53.934 4601 4669 D CameraStateRegistry: Camera State
01-25 04:14:53.934 4601 4669 D CameraStateRegistry: -------------------------------------------------------------------
01-25 04:14:53.934 4601 4669 D CameraStateRegistry: Camera@8592d18[id=0] CLOSED
01-25 04:14:53.934 4601 4669 D CameraStateRegistry: Camera@3b115ad[id=1] UNKNOWN
01-25 04:14:53.934 4601 4669 D CameraStateRegistry: -------------------------------------------------------------------
01-25 04:14:53.934 4601 4669 D CameraStateRegistry: Open count: 0 (Max allowed: 1)
01-25 04:14:53.934 4601 4669 D CameraStateMachine: New public camera state CameraState{type=CLOSED, error=null} from CLOSED and null
01-25 04:14:53.934 4601 4669 D CameraStateMachine: Publishing new public camera state CameraState{type=CLOSED, error=null}
--------- switch to events
01-25 04:15:01.276 4601 4601 I viewroot_draw_event: [window=VRI[PopupWindow:8bfa8e0],event=reportDrawFinished seqId=0]
01-25 04:15:01.278 4601 4601 I viewroot_draw_event: [window=VRI[PopupWindow:8bfa8e0],event=reportDrawFinished seqId=0]
--------- switch to main
01-25 04:15:03.787 4601 4601 W WindowOnBackDispatcher: sendCancelIfRunning: isInProgress=false callback=android.view.ViewRootImpl$$ExternalSyntheticLambda11@fb4d455
--------- switch to events
01-25 04:15:05.805 4601 4601 I wm_on_top_resumed_lost_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=topStateChangedWhenResumed]
01-25 04:15:05.840 4601 4601 I view_enqueue_input_event: [eventType=Motion - Cancel,action=app.attestation.auditor/app.attestation.auditor.AttestationActivity]
01-25 04:15:06.356 4601 4601 I wm_on_paused_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=performPause,time=0ms]
--------- switch to main
01-25 04:15:06.357 4601 4601 D VRI[AttestationActivity]: visibilityChanged oldVisibility=true newVisibility=false
--------- switch to events
01-25 04:15:06.371 4601 4601 I viewroot_draw_event: [window=VRI[AttestationActivity],event=Not drawing due to not visible. Reason=!mAppVisible && !mForceDecorViewVisibility]
01-25 04:15:06.373 4601 4601 I wm_on_stop_called: [Token=166671266,Component Name=app.attestation.auditor.AttestationActivity,Reason=STOP_ACTIVITY_ITEM,time=0ms]
When did you install GrapheneOS? Did you restore from a backup? Does it have internet access?
When did you install GrapheneOS? Did you restore from a backup? Does it have internet access?
netinstaller, edge on windows; at first i restore it from my previous 6a, after the issue i wipe it and install gos again and the issue still presenting; yes it has.
same error with a pixel 8a. Fresh install of grapheneOS with web installer with chrome on windows 11 + factory reset to try to resolve the probleme
Auditor log : Auditor_log_fa8843739390[1].txt
System log : System_log_68bf999bd19f[1].txt
I did get the same error from the 19 th of April. Last good attestation through attestation.app was the 18 th of April. I did not install new apps, only updates on my pixel 9 with GOS.
I added my log file from yesterday, because after that I cleared my auditor app and reconnected with the online attestation.app. After clearing, and reconnecting the attestation works again, but I'm not sure if this was the right solution?
But I'm questioning myself, what did happen to my auditor app or GOS system, so it wasn't working anymore?
Having the same issue on Pixel 8