java.security.ProviderException: Failed to construct key object from newly generated key pair. #284
Description
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]