search

sqlcipher / android-database-sqlcipher

Android SQLite API based on SQLCipher
https://www.zetetic.net/sqlcipher/sqlcipher-for-android/
Other
2.73k stars 564 forks source link

Crash: signal 11 (SIGSEGV), code 1 (SEGV_MAPERR), fault addr 0x54 #508

Closed aeroxr1 closed 4 years ago

aeroxr1 commented 4 years ago

Hi, i'm having the following crash:

2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: Build fingerprint: 'samsung/gts6leea/gts6l:9/PPR1.180610.011/T865XXU2ASL3:user/release-keys' 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: Revision: '8' 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: ABI: 'arm64' 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: pid: 9824, tid: 9893, name: AsyncTask #1 >>> net.zetetic.sqlcipher.test <<< 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: signal 11 (SIGSEGV), code 1 (SEGV_MAPERR), fault addr 0x54 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: Cause: null pointer dereference 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: x0 000000756a0fc6c0 x1 0000000000000000 x2 0000000000000000 x3 000000757bd3fed5 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: x4 000000757bc6b8f5 x5 0000000000000020 x6 797469746e652e24 x7 656d6f747375632e 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: x8 0000000000000033 x9 0000000000000000 x10 0000000000000001 x11 000000757bd857a0 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: x12 0000000000000001 x13 00644972656d6f74 x14 0000000000000080 x15 aaaaaaaaaaaaaaab 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: x16 0000007569fb3f40 x17 000000760a07e3c0 x18 0000000000000000 x19 000000757bd85598 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: x20 000000757bd1f408 x21 0000000000000000 x22 000000757bd85598 x23 000000757bd85570 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: x24 0000000000000033 x25 000000757bd85598 x26 0000000000000000 x27 0000000000000001 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: x28 000000757bd85508 x29 000000756a0fb1a0 2020-04-22 08:39:01.410 9899-9899/? A/DEBUG: sp 000000756a0fb080 lr 0000007569d8982c pc 0000007569d8b5e4 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: backtrace: 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #00 pc 000000000011b5e4 /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #01 pc 000000000010add4 /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #02 pc 000000000011864c /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #03 pc 000000000010d438 /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #04 pc 00000000000d7b94 /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #05 pc 00000000000bd85c /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #06 pc 000000000006f7ec /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #07 pc 000000000006d534 /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #08 pc 000000000006e4c0 /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #09 pc 000000000006e508 /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so (sqlite3_prepare16_v2+40) 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #10 pc 00000000001b420c /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so (sqlcipher::compile(_JNIEnv, _jobject, sqlite3, _jstring)+184) 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #11 pc 00000000001b446c /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/lib/arm64/libsqlcipher.so 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #12 pc 0000000000561fe0 /system/lib64/libart.so (art_quick_generic_jni_trampoline+144) 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #13 pc 0000000000558f88 /system/lib64/libart.so (art_quick_invoke_stub+584) 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #14 pc 00000000000cfcc8 /system/lib64/libart.so (art::ArtMethod::Invoke(art::Thread, unsigned int, unsigned int, art::JValue, char const)+200) 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #15 pc 00000000002806c0 /system/lib64/libart.so (art::interpreter::ArtInterpreterToCompiledCodeBridge(art::Thread, art::ArtMethod, art::ShadowFrame, unsigned short, art::JValue)+344) 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #16 pc 000000000027a6c8 /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+968) 2020-04-22 08:39:01.447 9899-9899/? A/DEBUG: #17 pc 00000000005291cc /system/lib64/libart.so (MterpInvokeDirect+296) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #18 pc 000000000054b614 /system/lib64/libart.so (ExecuteMterpImpl+14484) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #19 pc 0000000000150dd6 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.sqlcipher.database.SQLiteCompiledSql.compile+30) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #20 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #21 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #22 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #23 pc 00000000005291cc /system/lib64/libart.so (MterpInvokeDirect+296) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #24 pc 000000000054b614 /system/lib64/libart.so (ExecuteMterpImpl+14484) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #25 pc 0000000000150d5e /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.sqlcipher.database.SQLiteCompiledSql.+86) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #26 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #27 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #28 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #29 pc 00000000005291cc /system/lib64/libart.so (MterpInvokeDirect+296) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #30 pc 000000000054b614 /system/lib64/libart.so (ExecuteMterpImpl+14484) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #31 pc 0000000000156418 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.sqlcipher.database.SQLiteProgram.+224) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #32 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #33 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #34 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #35 pc 00000000005291cc /system/lib64/libart.so (MterpInvokeDirect+296) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #36 pc 000000000054b614 /system/lib64/libart.so (ExecuteMterpImpl+14484) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #37 pc 0000000000157480 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.sqlcipher.database.SQLiteQuery.) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #38 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #39 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #40 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #41 pc 00000000005291cc /system/lib64/libart.so (MterpInvokeDirect+296) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #42 pc 000000000054b614 /system/lib64/libart.so (ExecuteMterpImpl+14484) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #43 pc 00000000001557da /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.sqlcipher.database.SQLiteDirectCursorDriver.query+14) 2020-04-22 08:39:01.448 9899-9899/? A/DEBUG: #44 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #45 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #46 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #47 pc 0000000000527e8c /system/lib64/libart.so (MterpInvokeVirtual+588) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #48 pc 000000000054b514 /system/lib64/libart.so (ExecuteMterpImpl+14228) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #49 pc 000000000015386e /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.sqlcipher.database.SQLiteDatabase.rawQuery+82) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #50 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #51 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #52 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #53 pc 0000000000527e8c /system/lib64/libart.so (MterpInvokeVirtual+588) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #54 pc 000000000054b514 /system/lib64/libart.so (ExecuteMterpImpl+14228) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #55 pc 0000000000162c3c /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.zetetic.tests.Testapp.retrieveRowArrays+36) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #56 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #57 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #58 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #59 pc 0000000000527e8c /system/lib64/libart.so (MterpInvokeVirtual+588) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #60 pc 000000000054b514 /system/lib64/libart.so (ExecuteMterpImpl+14228) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #61 pc 0000000000162d50 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.zetetic.tests.Testapp.selectWithQueryArrays+32) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #62 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #63 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #64 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #65 pc 0000000000527e8c /system/lib64/libart.so (MterpInvokeVirtual+588) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #66 pc 000000000054b514 /system/lib64/libart.so (ExecuteMterpImpl+14228) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #67 pc 0000000000162b72 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.zetetic.tests.Testapp.execute+46) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #68 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #69 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #70 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #71 pc 0000000000527e8c /system/lib64/libart.so (MterpInvokeVirtual+588) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #72 pc 000000000054b514 /system/lib64/libart.so (ExecuteMterpImpl+14228) 2020-04-22 08:39:01.449 9899-9899/? A/DEBUG: #73 pc 00000000001616fa /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.zetetic.tests.SQLCipherTest.run+46) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #74 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #75 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #76 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #77 pc 0000000000527e8c /system/lib64/libart.so (MterpInvokeVirtual+588) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #78 pc 000000000054b514 /system/lib64/libart.so (ExecuteMterpImpl+14228) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #79 pc 000000000016311c /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.zetetic.tests.TestSuiteRunner.runSuite+124) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #80 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #81 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #82 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #83 pc 00000000005291cc /system/lib64/libart.so (MterpInvokeDirect+296) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #84 pc 000000000054b614 /system/lib64/libart.so (ExecuteMterpImpl+14484) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #85 pc 0000000000162f7e /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.zetetic.tests.TestSuiteRunner.doInBackground+46) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #86 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #87 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #88 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #89 pc 0000000000527e8c /system/lib64/libart.so (MterpInvokeVirtual+588) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #90 pc 000000000054b514 /system/lib64/libart.so (ExecuteMterpImpl+14228) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #91 pc 0000000000162f34 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-o-LHeCfitleKluM8wl30sw==/base.apk_9824_9824 (deleted) (net.zetetic.tests.TestSuiteRunner.doInBackground+4) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #92 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #93 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #94 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #95 pc 0000000000527e8c /system/lib64/libart.so (MterpInvokeVirtual+588) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #96 pc 000000000054b514 /system/lib64/libart.so (ExecuteMterpImpl+14228) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #97 pc 0000000000c91e76 /system/framework/boot-framework.vdex (android.os.AsyncTask$2.call+40) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #98 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #99 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #100 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #101 pc 0000000000528e08 /system/lib64/libart.so (MterpInvokeInterface+1392) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #102 pc 000000000054b714 /system/lib64/libart.so (ExecuteMterpImpl+14740) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #103 pc 00000000001134be /system/framework/boot.vdex (java.util.concurrent.FutureTask.run+62) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #104 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.450 9899-9899/? A/DEBUG: #105 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #106 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #107 pc 0000000000528e08 /system/lib64/libart.so (MterpInvokeInterface+1392) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #108 pc 000000000054b714 /system/lib64/libart.so (ExecuteMterpImpl+14740) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #109 pc 0000000000c91f94 /system/framework/boot-framework.vdex (android.os.AsyncTask$SerialExecutor$1.run+4) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #110 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #111 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #112 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #113 pc 0000000000528e08 /system/lib64/libart.so (MterpInvokeInterface+1392) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #114 pc 000000000054b714 /system/lib64/libart.so (ExecuteMterpImpl+14740) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #115 pc 00000000001174a4 /system/framework/boot.vdex (java.util.concurrent.ThreadPoolExecutor.runWorker+162) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #116 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #117 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #118 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #119 pc 0000000000527e8c /system/lib64/libart.so (MterpInvokeVirtual+588) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #120 pc 000000000054b514 /system/lib64/libart.so (ExecuteMterpImpl+14228) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #121 pc 00000000001167d2 /system/framework/boot.vdex (java.util.concurrent.ThreadPoolExecutor$Worker.run+4) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #122 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #123 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #124 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #125 pc 0000000000528e08 /system/lib64/libart.so (MterpInvokeInterface+1392) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #126 pc 000000000054b714 /system/lib64/libart.so (ExecuteMterpImpl+14740) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #127 pc 00000000000caa5a /system/framework/boot.vdex (java.lang.Thread.run+12) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #128 pc 000000000025431c /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+488) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #129 pc 0000000000518720 /system/lib64/libart.so (artQuickToInterpreterBridge+1020) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #130 pc 00000000005620fc /system/lib64/libart.so (art_quick_to_interpreter_bridge+92) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #131 pc 0000000000558f88 /system/lib64/libart.so (art_quick_invoke_stub+584) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #132 pc 00000000000cfcc8 /system/lib64/libart.so (art::ArtMethod::Invoke(art::Thread, unsigned int, unsigned int, art::JValue, char const)+200) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #133 pc 000000000045e6b8 /system/lib64/libart.so (art::(anonymous namespace)::InvokeWithArgArray(art::ScopedObjectAccessAlreadyRunnable const&, art::ArtMethod, art::(anonymous namespace)::ArgArray, art::JValue, char const)+104) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #134 pc 000000000045f774 /system/lib64/libart.so (art::InvokeVirtualOrInterfaceWithJValues(art::ScopedObjectAccessAlreadyRunnable const&, _jobject, _jmethodID, jvalue)+424) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #135 pc 000000000048b07c /system/lib64/libart.so (art::Thread::CreateCallback(void)+1120) 2020-04-22 08:39:01.451 9899-9899/? A/DEBUG: #136 pc 0000000000091d34 /system/lib64/libc.so (__pthread_start(void*)+36) 2020-04-22 08:39:01.452 9899-9899/? A/DEBUG: #137 pc 0000000000024250 /system/lib64/libc.so (__start_thread+68)

I'm able to reproduce the crash on the testSuite both with last library version 4.3.0 and 4.2.0 (the version that I'm currently using on my app)

Can you help me ?

developernotes commented 4 years ago

Hi @aeroxr1

I'm able to reproduce the crash on the testSuite both with last library version 4.3.0 and 4.2.0 (the version that I'm currently using on my app) The pragma version is: 3.4.1

SQLCipher for Android cipher_version 3.4.1 is not used in either the 4.3.0, or 4.2.0 releases. Can you provide more information about the test that is crashing, and the device you are running this on? Did you write a new test, or is this an existing test?

aeroxr1 commented 4 years ago

Sorry, the pragma version was wrong ignore it. I'm having this crash on my db in production, so I have created a new test importing my db. How can you help me to find the cause of crash ?

developernotes commented 4 years ago

Hi @aeroxr1

Can you post the test scenario? Also, can you provide a Java stack trace associated with the crash of the test? More information about the scenario is needed to help debug further.

aeroxr1 commented 4 years ago

The db i'm using is json1 without password. A specific query is causing the crash in some cases for example on production db and not on dev db. The crash happens executing both database.rawQuery(query, bindingArgs); and database.query(query); Following the java stack trace:

2020-04-22 16:40:58.034 4388-4456/net.zetetic.sqlcipher.test W/.sqlcipher.tes: 0xebadde09 skipped times: 0

--------- beginning of crash

2020-04-22 16:40:58.036 4388-4456/net.zetetic.sqlcipher.test A/libc: Fatal signal 11 (SIGSEGV), code 1 (SEGV_MAPERR), fault addr 0x54 in tid 4456 (AsyncTask #1), pid 4388 (.sqlcipher.test) 2020-04-22 16:40:58.083 4524-4524/? E/crash_dump64: unknown process state: t 2020-04-22 16:40:58.114 4524-4524/? I/crash_dump64: obtaining output fd from tombstoned, type: kDebuggerdTombstone 2020-04-22 16:40:58.116 1088-1088/? I//system/bin/tombstoned: received crash request for pid 4456 2020-04-22 16:40:58.117 4524-4524/? I/crash_dump64: performing dump of process 4388 (target tid = 4456) 2020-04-22 16:40:58.124 4524-4524/? A/DEBUG: 2020-04-22 16:40:58.124 4524-4524/? A/DEBUG: Build fingerprint: 'samsung/gts6leea/gts6l:9/PPR1.180610.011/T865XXU2ASL3:user/release-keys' 2020-04-22 16:40:58.124 4524-4524/? A/DEBUG: Revision: '8' 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: ABI: 'arm64' 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: pid: 4388, tid: 4456, name: AsyncTask #1 >>> net.zetetic.sqlcipher.test <<< 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: signal 11 (SIGSEGV), code 1 (SEGV_MAPERR), fault addr 0x54 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: Cause: null pointer dereference 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: x0 00000075696fb460 x1 0000000000000000 x2 0000000000000000 x3 000000756c1ca555 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: x4 000000756a2ace75 x5 0000000000000020 x6 797469746e652e24 x7 656d6f747375632e 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: x8 0000000000000033 x9 0000000000000000 x10 0000000000000001 x11 0000007568e118a0 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: x12 0000000000000001 x13 00644972656d6f74 x14 0000000000000050 x15 aaaaaaaaaaaaaaab 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: x16 00000075695b2f40 x17 000000760a07e3c0 x18 0000000000000000 x19 0000007568e11698 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: x20 000000756a21b8e8 x21 0000000000000000 x22 0000007568e11698 x23 0000007568e11670 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: x24 0000000000000033 x25 0000007568e11698 x26 0000000000000000 x27 0000000000000001 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: x28 0000007568e11608 x29 00000075696f9f40 2020-04-22 16:40:58.125 4524-4524/? A/DEBUG: sp 00000075696f9e20 lr 000000756938882c pc 000000756938a5e4 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: backtrace: 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #00 pc 000000000011b5e4 /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #01 pc 000000000010add4 /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #02 pc 000000000011864c /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #03 pc 000000000010d438 /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #04 pc 00000000000d7b94 /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #05 pc 00000000000bd85c /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #06 pc 000000000006f7ec /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #07 pc 000000000006d534 /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #08 pc 000000000006e4c0 /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #09 pc 000000000006e508 /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so (sqlite3_prepare16_v2+40) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #10 pc 00000000001b420c /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so (sqlcipher::compile(_JNIEnv, _jobject, sqlite3, _jstring)+184) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #11 pc 00000000001b446c /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/lib/arm64/libsqlcipher.so 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #12 pc 0000000000561fe0 /system/lib64/libart.so (art_quick_generic_jni_trampoline+144) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #13 pc 0000000000558f88 /system/lib64/libart.so (art_quick_invoke_stub+584) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #14 pc 00000000000cfcc8 /system/lib64/libart.so (art::ArtMethod::Invoke(art::Thread, unsigned int, unsigned int, art::JValue, char const)+200) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #15 pc 00000000002806c0 /system/lib64/libart.so (art::interpreter::ArtInterpreterToCompiledCodeBridge(art::Thread, art::ArtMethod, art::ShadowFrame, unsigned short, art::JValue)+344) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #16 pc 000000000027a6c8 /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+968) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #17 pc 00000000002ae5f0 /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE1ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+248) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #18 pc 00000000002ab7b0 /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+62724) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #19 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #20 pc 0000000000150dc4 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.sqlcipher.database.SQLiteCompiledSql.compile) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #21 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #22 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #23 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #24 pc 00000000002ae5f0 /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE1ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+248) 2020-04-22 16:40:58.144 4524-4524/? A/DEBUG: #25 pc 00000000002ab7b0 /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+62724) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #26 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #27 pc 0000000000150d14 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.sqlcipher.database.SQLiteCompiledSql.) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #28 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #29 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #30 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #31 pc 00000000002ae5f0 /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE1ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+248) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #32 pc 00000000002ab7b0 /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+62724) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #33 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #34 pc 0000000000156344 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.sqlcipher.database.SQLiteProgram.) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #35 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #36 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #37 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #38 pc 00000000002ae5f0 /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE1ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+248) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #39 pc 00000000002ab7b0 /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+62724) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #40 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #41 pc 000000000015748c /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.sqlcipher.database.SQLiteQuery.) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #42 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #43 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #44 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #45 pc 00000000002ae5f0 /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE1ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+248) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #46 pc 00000000002ab7b0 /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+62724) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #47 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #48 pc 00000000001557d8 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.sqlcipher.database.SQLiteDirectCursorDriver.query) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #49 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #50 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.145 4524-4524/? A/DEBUG: #51 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #52 pc 00000000002ace1c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE2ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+580) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #53 pc 00000000002aaedc /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+60464) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #54 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #55 pc 0000000000153828 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.sqlcipher.database.SQLiteDatabase.rawQuery) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #56 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #57 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #58 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #59 pc 00000000002ace1c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE2ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+580) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #60 pc 00000000002aaedc /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+60464) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #61 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #62 pc 0000000000162c6c /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.zetetic.tests.Testapp.retrieveRowArrays) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #63 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #64 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #65 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #66 pc 00000000002ace1c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE2ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+580) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #67 pc 00000000002aaedc /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+60464) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #68 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #69 pc 0000000000162d54 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.zetetic.tests.Testapp.selectWithQueryArrays) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #70 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #71 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #72 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #73 pc 00000000002ace1c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE2ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+580) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #74 pc 00000000002aaedc /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+60464) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #75 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #76 pc 0000000000162b50 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.zetetic.tests.Testapp.execute) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #77 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #78 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.146 4524-4524/? A/DEBUG: #79 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #80 pc 00000000002ace1c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE2ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+580) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #81 pc 00000000002aaedc /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+60464) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #82 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #83 pc 00000000001616d8 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.zetetic.tests.SQLCipherTest.run) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #84 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #85 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #86 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #87 pc 00000000002ace1c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE2ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+580) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #88 pc 00000000002aaedc /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+60464) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #89 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #90 pc 00000000001630c4 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.zetetic.tests.TestSuiteRunner.runSuite) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #91 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #92 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #93 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #94 pc 00000000002ae5f0 /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE1ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+248) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #95 pc 00000000002ab7b0 /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+62724) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #96 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #97 pc 0000000000162f74 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.zetetic.tests.TestSuiteRunner.doInBackground) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #98 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #99 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #100 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #101 pc 00000000002ace1c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE2ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+580) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #102 pc 00000000002aaedc /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+60464) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #103 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #104 pc 0000000000162f54 /dev/ashmem/dalvik-classes.dex extracted in memory from /data/app/net.zetetic.sqlcipher.test-21_0b-AMha_rjrA6RR-TXw==/base.apk_4388_4388 (deleted) (net.zetetic.tests.TestSuiteRunner.doInBackground) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #105 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.147 4524-4524/? A/DEBUG: #106 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #107 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #108 pc 00000000002ace1c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE2ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+580) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #109 pc 00000000002aaedc /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+60464) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #110 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #111 pc 0000000000c91e4e /system/framework/boot-framework.vdex (android.os.AsyncTask$2.call) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #112 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #113 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #114 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #115 pc 00000000002aee6c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE4ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+1392) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #116 pc 00000000002a8688 /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+50140) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #117 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #118 pc 0000000000113480 /system/framework/boot.vdex (java.util.concurrent.FutureTask.run) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #119 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #120 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #121 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #122 pc 00000000002aee6c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE4ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+1392) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #123 pc 00000000002a8688 /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+50140) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #124 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #125 pc 0000000000c91f90 /system/framework/boot-framework.vdex (android.os.AsyncTask$SerialExecutor$1.run) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #126 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #127 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #128 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #129 pc 00000000002aee6c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE4ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+1392) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #130 pc 00000000002a8688 /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+50140) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #131 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #132 pc 0000000000117402 /system/framework/boot.vdex (java.util.concurrent.ThreadPoolExecutor.runWorker) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #133 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #134 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #135 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #136 pc 00000000002ace1c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE2ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+580) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #137 pc 00000000002aaedc /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+60464) 2020-04-22 16:40:58.148 4524-4524/? A/DEBUG: #138 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #139 pc 00000000001167ce /system/framework/boot.vdex (java.util.concurrent.ThreadPoolExecutor$Worker.run) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #140 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #141 pc 0000000000259e10 /system/lib64/libart.so (art::interpreter::ArtInterpreterToInterpreterBridge(art::Thread, art::CodeItemDataAccessor const&, art::ShadowFrame, art::JValue)+216) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #142 pc 000000000027a6ac /system/lib64/libart.so (bool art::interpreter::DoCall<false, false>(art::ArtMethod, art::Thread, art::ShadowFrame&, art::Instruction const, unsigned short, art::JValue)+940) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #143 pc 00000000002aee6c /system/lib64/libart.so (_ZN3art11interpreterL8DoInvokeILNS_10InvokeTypeE4ELb0ELb0EEEbPNS_6ThreadERNS_11ShadowFrameEPKNS_11InstructionEtPNS_6JValueE+1392) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #144 pc 00000000002a8688 /system/lib64/libart.so (void art::interpreter::ExecuteSwitchImplCpp<false, false>(art::interpreter::SwitchImplContext)+50140) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #145 pc 0000000000564938 /system/lib64/libart.so (ExecuteSwitchImplAsm+8) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #146 pc 00000000000caa4e /system/framework/boot.vdex (java.lang.Thread.run) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #147 pc 00000000002542bc /system/lib64/libart.so (_ZN3art11interpreterL7ExecuteEPNS_6ThreadERKNS_20CodeItemDataAccessorERNS_11ShadowFrameENS_6JValueEb.llvm.2923458455+392) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #148 pc 0000000000518720 /system/lib64/libart.so (artQuickToInterpreterBridge+1020) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #149 pc 00000000005620fc /system/lib64/libart.so (art_quick_to_interpreter_bridge+92) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #150 pc 0000000000558f88 /system/lib64/libart.so (art_quick_invoke_stub+584) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #151 pc 00000000000cfcc8 /system/lib64/libart.so (art::ArtMethod::Invoke(art::Thread, unsigned int, unsigned int, art::JValue, char const)+200) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #152 pc 000000000045e6b8 /system/lib64/libart.so (art::(anonymous namespace)::InvokeWithArgArray(art::ScopedObjectAccessAlreadyRunnable const&, art::ArtMethod, art::(anonymous namespace)::ArgArray, art::JValue, char const)+104) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #153 pc 000000000045f774 /system/lib64/libart.so (art::InvokeVirtualOrInterfaceWithJValues(art::ScopedObjectAccessAlreadyRunnable const&, _jobject, _jmethodID, jvalue)+424) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #154 pc 000000000048b07c /system/lib64/libart.so (art::Thread::CreateCallback(void)+1120) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #155 pc 0000000000091d34 /system/lib64/libc.so (__pthread_start(void)+36) 2020-04-22 16:40:58.149 4524-4524/? A/DEBUG: #156 pc 0000000000024250 /system/lib64/libc.so (__start_thread+68) 2020-04-22 16:40:58.209 779-3955/? I/sensors-hal: handle_sns_std_sensor_event:92, [SSC_LIGHT] ambient_light lux: 24, ts=717623298743693 2020-04-22 16:40:58.317 1088-1088/? E//system/bin/tombstoned: Tombstone written to: /data/tombstones/tombstone_00 2020-04-22 16:40:58.318 686-686/? E/audit: type=1701 audit(1587566458.312:12206): auid=4294967295 uid=10562 gid=10562 ses=4294967295 subj=u:r:untrusted_app:s0:c50,c258,c512,c768 pid=4388 comm=4173796E635461736B202331 exe="/system/bin/app_process64" sig=11 res=1

--------- beginning of system

2020-04-22 16:40:58.319 1449-4527/? W/ActivityManager: crash : net.zetetic.sqlcipher.test,0 2020-04-22 16:40:58.322 1449-4527/? W/ActivityManager: Force finishing activity net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity 2020-04-22 16:40:58.323 1449-1500/? I/BootReceiver: Copying /data/tombstones/tombstone_00 to DropBox (SYSTEM_TOMBSTONE) 2020-04-22 16:40:58.323 1449-1449/? D/Telecom:SamsungPrebindingServiceImpl: onTopActivityChanged : ComponentInfo{net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity} 2020-04-22 16:40:58.331 1449-4527/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.334 1449-1635/? W/InputDispatcher: channel '6d07297 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity (server)' ~ Consumer closed input channel or an error occurred. events=0x9, fd=988 2020-04-22 16:40:58.334 1449-1635/? E/InputDispatcher: channel '6d07297 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity (server)' ~ Channel is unrecoverably broken and will be disposed! 2020-04-22 16:40:58.342 1449-1635/? W/InputDispatcher: channel 'd3b2351 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity (server)' ~ Consumer closed input channel or an error occurred. events=0x9, fd=573 2020-04-22 16:40:58.343 1449-1635/? E/InputDispatcher: channel 'd3b2351 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity (server)' ~ Channel is unrecoverably broken and will be disposed! 2020-04-22 16:40:58.344 1449-3413/? D/InputTransport: Input channel destroyed: fd=811 2020-04-22 16:40:58.344 1449-3393/? I/WindowManager: WIN DEATH: Window{d3b2351 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity} 2020-04-22 16:40:58.344 1449-3393/? W/InputDispatcher: Attempted to unregister already unregistered input channel 'd3b2351 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity (server)' 2020-04-22 16:40:58.344 1449-3393/? V/WindowManager: Remove Window{d3b2351 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity}: mSurfaceController=null mAnimatingExit=false mRemoveOnExit=false mHasSurface=false surfaceShowing=false isAnimationSet=false app-animation=false mWillReplaceWindow=false inPendingTransaction=false mDisplayFrozen=false callers=com.android.server.wm.WindowState.access$300:283 com.android.server.wm.WindowState$DeathRecipient.binderDied:3421 android.os.BinderProxy.sendDeathNotice:1211 2020-04-22 16:40:58.344 14439-14439/? I/BatteryReceiver: Received : com.samsung.android.sm.ACTION_ERROR 2020-04-22 16:40:58.344 709-709/? I/Zygote: Process 4388 exited due to signal (11) 2020-04-22 16:40:58.345 16321-16321/? D/InputTransport: Input channel destroyed: fd=68 2020-04-22 16:40:58.345 1449-3393/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.345 820-1422/? I/SurfaceFlinger: id=35474 Removed d3b2351 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity#0 (0/57) 2020-04-22 16:40:58.345 14439-14439/? W/ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.startService:1610 android.content.ContextWrapper.startService:677 com.samsung.android.sm.battery.receiver.BatteryReceiver.e:186 com.samsung.android.sm.battery.receiver.BatteryReceiver.onReceive:66 android.app.ActivityThread.handleReceiver:3649 2020-04-22 16:40:58.345 1449-3393/? D/InputTransport: Input channel destroyed: fd=573 2020-04-22 16:40:58.346 1449-3392/? I/ActivityManager: Process net.zetetic.sqlcipher.test (pid 4388) has died: fore TOP (519,2154) 2020-04-22 16:40:58.346 1449-3393/? V/WindowManager: Changing focus from Window{6d07297 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity} to null Callers=com.android.server.wm.WindowState.removeIfPossible:2938 com.android.server.wm.WindowState.access$300:283 com.android.server.wm.WindowState$DeathRecipient.binderDied:3421 android.os.BinderProxy.sendDeathNotice:1211 2020-04-22 16:40:58.346 1449-1498/? W/libprocessgroup: kill(-4388, 9) failed: No such process 2020-04-22 16:40:58.346 1449-1498/? I/libprocessgroup: Successfully killed process cgroup uid 10562 pid 4388 in 0ms 2020-04-22 16:40:58.346 1449-3393/? D/InputDispatcher: Focus left window (4388): 6d07297 0 2020-04-22 16:40:58.346 1449-1476/? I/WindowManager: WIN DEATH: Window{6d07297 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity} 2020-04-22 16:40:58.346 1449-1476/? W/InputDispatcher: Attempted to unregister already unregistered input channel '6d07297 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity (server)' 2020-04-22 16:40:58.346 1449-1476/? V/WindowManager: Remove Window{6d07297 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity}: mSurfaceController=Surface(name=net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity[4388])/@0x174fc84 mAnimatingExit=false mRemoveOnExit=false mHasSurface=true surfaceShowing=true isAnimationSet=true app-animation=true mWillReplaceWindow=false inPendingTransaction=true mDisplayFrozen=false callers=com.android.server.wm.WindowState.access$300:283 com.android.server.wm.WindowState$DeathRecipient.binderDied:3421 android.os.BinderProxy.sendDeathNotice:1211 2020-04-22 16:40:58.348 1449-1476/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.350 1449-1476/? D/InputTransport: Input channel destroyed: fd=988 2020-04-22 16:40:58.352 1449-3392/? V/WindowManager: Setting visibility of Window{6d07297 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity EXITING}: false, caller=com.android.server.wm.WindowContainer.sendAppVisibilityToClients:615 com.android.server.wm.AppWindowToken.setClientHidden:469 com.android.server.wm.AppWindowToken.onAnimationFinished:2464 com.android.server.wm.AppWindowToken.setVisibility:560 com.android.server.wm.AppWindowToken.onRemovedFromDisplay:792 2020-04-22 16:40:58.352 1449-3392/? E/WindowManager: win=Window{6d07297 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity EXITING} destroySurfaces: appStopped=false win.mWindowRemovalAllowed=true win.mRemoveOnExit=true win.mViewVisibility=0 caller=com.android.server.wm.AppWindowToken.destroySurfaces:896 com.android.server.wm.AppWindowToken.destroySurfaces:877 com.android.server.wm.WindowState.onExitAnimationDone:5496 com.android.server.wm.-$$Lambda$01bPtngJg5AqEoOWfW3rWfV7MH4.accept:2 java.util.ArrayList.forEach:1262 com.android.server.wm.AppWindowToken.onAnimationFinished:2479 com.android.server.wm.AppWindowToken.setVisibility:560 2020-04-22 16:40:58.352 1449-3392/? I/WindowManager: Destroying surface Surface(name=net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity[4388])/@0x174fc84 called by com.android.server.wm.WindowStateAnimator.destroySurface:1884 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:821 com.android.server.wm.WindowState.destroySurfaceUnchecked:3882 com.android.server.wm.WindowState.destroySurface:3863 com.android.server.wm.AppWindowToken.destroySurfaces:896 com.android.server.wm.AppWindowToken.destroySurfaces:877 com.android.server.wm.WindowState.onExitAnimationDone:5496 com.android.server.wm.-$$Lambda$01bPtngJg5AqEoOWfW3rWfV7MH4.accept:2 2020-04-22 16:40:58.353 820-1422/? I/SurfaceFlinger: id=35479 Removed net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity[4388]#0 (0/56) 2020-04-22 16:40:58.354 1449-3392/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.355 820-2287/? W/SurfaceFlinger: Attempting to set client state on removed layer: net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity[4388]#0 [s.what : 40] 2020-04-22 16:40:58.355 820-2287/? I/SurfaceFlinger: id=35478 Removed 6d07297 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity#0 (0/55) 2020-04-22 16:40:58.355 820-2287/? W/SurfaceFlinger: Attempting to destroy on removed layer: net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity[4388]#0 2020-04-22 16:40:58.356 1449-3392/? D/PowerManagerService: [api] release WakeLock SCREEN_BRIGHT_WAKE_LOCK 'WindowManager' ON_AFTER_RELEASE ACQ=-31s206ms (uid=1000 pid=1449 ws=WorkSource{10562}) (0x0) 2020-04-22 16:40:58.356 1449-3392/? D/PowerManagerService: [api] applyWakeLockFlagsOnReleaseLocked : userActivityNoUpdateLocked is called : SCREEN_BRIGHT_WAKE_LOCK 'WindowManager' ON_AFTER_RELEASE (uid=1000 pid=1449 ws=WorkSource{10562}) 2020-04-22 16:40:58.358 1449-3392/? I/WindowManager: Cancelling animation restarting=false, leash=null, surface=Surface(name=AppWindowToken{6be0ad8 token=Token{f7e35bb ActivityRecord{2b8824a u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity t870}}})/@0xeb5da08, parent=Surface(name=Task=870)/@0x3d85792 2020-04-22 16:40:58.358 1449-3392/? D/InputDispatcher: Focused application released 2020-04-22 16:40:58.359 3223-3223/? D/SDHMS:g: writeSysfs:: path = /sys/devices/system/cpu/cpu0/cpufreq/schedutil/down_rate_limit_us, value = 0 2020-04-22 16:40:58.359 820-820/? I/Layer: id=35474 onRemoved d3b2351 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity#0 2020-04-22 16:40:58.359 820-820/? I/Layer: id=35478 onRemoved 6d07297 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity#0 2020-04-22 16:40:58.359 820-820/? I/Layer: id=35479 onRemoved net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity[4388]#0 2020-04-22 16:40:58.359 3223-3223/? D/SDHMS:g: writeSysfs:: path = /sys/devices/system/cpu/cpu4/cpufreq/schedutil/down_rate_limit_us, value = 0 2020-04-22 16:40:58.360 3223-3223/? D/SDHMS:g: writeSysfs:: path = /sys/devices/system/cpu/cpu7/cpufreq/schedutil/down_rate_limit_us, value = 0 2020-04-22 16:40:58.360 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.362 820-898/? I/SurfaceFlinger: id=35477 Removed AppWindowToken{6be0ad8 token=Token{f7e35bb ActivityRecord{2b8824a u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity t870}}}#0 (1/54) 2020-04-22 16:40:58.364 820-820/? I/SurfaceFlinger: Display 0 HWC layers: type | handle | flag | format | source crop (l,t,r,b) | frame | name ------------+--------------+------+-----------+----------------------------+---------------------+------ Device | 0x7f0a62e110 | 0000 | RGBA_8888 | 0.0 0.0 2560.0 54.0 | 0 0 2560 54 | StatusBar[1905]#0 Device | 0x7f0a62d2a0 | 0000 | RGBA_8888 | 0.0 0.0 45.0 1600.0 | 2515 0 2560 1600 | MultiWindowGestureDetector[1905]#0 Device | 0x7f0a62dc40 | 0000 | RGBA_8888 | 0.0 0.0 2560.0 335.0 | 0 1265 2560 1600 | NavbarGestureHandler[1905]#0 Device | 0x7f0aa3a630 | 0000 | RGBA_8888 | 0.0 0.0 23.0 1600.0 | 0 0 23 1600 | ScreenDecorOverlay[1905]#0 Device | 0x7f0aa3a710 | 0000 | RGBA_8888 | 0.0 0.0 23.0 1600.0 | 2537 0 2560 1600 | ScreenDecorOverlayBottom[1905]#0 2020-04-22 16:40:58.364 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.366 1449-1507/? D/WindowManager: reportFocusChangedSerialized, focused=false, inTouchMode=true, win=Window{6d07297 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity} 2020-04-22 16:40:58.366 1449-1507/? E/WindowManager: RemoteException occurs on reporting focusChanged, w=Window{6d07297 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity} android.os.DeadObjectException at android.os.BinderProxy.transactNative(Native Method) at android.os.BinderProxy.transact(Binder.java:1145) at android.view.IWindow$Stub$Proxy.windowFocusChanged(IWindow.java:500) at com.android.server.wm.WindowState.reportFocusChangedSerialized(WindowState.java:3986) at com.android.server.wm.WindowManagerService$H.handleMessage(WindowManagerService.java:5532) at android.os.Handler.dispatchMessage(Handler.java:106) at android.os.Looper.loop(Looper.java:216) at android.os.HandlerThread.run(HandlerThread.java:65) at com.android.server.ServiceThread.run(ServiceThread.java:44) 2020-04-22 16:40:58.367 786-839/? E/ANDR-PERF-MPCTL: Invalid profile no. 0, total profiles 0 only 2020-04-22 16:40:58.370 1449-3392/? D/CustomFrequencyManagerService: acquireDVFSLockLocked : type : DVFS_MIN_LIMIT frequency : 1612800 uid : 1000 pid : 1449 pkgName : AMS_ACT_RESUME@CPU_MIN@72 2020-04-22 16:40:58.370 1449-3392/? D/ActivityManagerPerformance: AMP_acquire() ACT_RESUME 2020-04-22 16:40:58.371 1449-3392/? I/ActivityManager: mTimer start at this point for Notification solution 2020-04-22 16:40:58.371 1449-3392/? I/ActivityManager: mTimer scheduling done 2020-04-22 16:40:58.372 1449-1572/? I/DecorView: mWindow.mActivityCurrentConfig is null. 2020-04-22 16:40:58.372 1449-2080/? D/libperflog: [PERFMON] perflog_write_log sock = 454 2020-04-22 16:40:58.372 1449-2080/? D/libperflog: [PERFMON] current_write_len = 173 2020-04-22 16:40:58.372 1449-2080/? D/libperflog: [LIBPERFLOG] perflog_write_log sent logtype=1, pid=0, tid=1449, [ActivityLaunch] [S] net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity 532520 2206040 2046232 1 30 1 32 72799316 2096858 -1 -1 -1 -1 -1 -1 -1 35 2020-04-22 16:40:58.373 1449-3392/? D/StorageManagerService: getExternalStorageMountMode : 1 2020-04-22 16:40:58.373 1449-2080/? D/libperflog: [LIBPERFLOG] perflog_write_evt sock = 454 2020-04-22 16:40:58.373 1449-2080/? D/libperflog: [LIBPERFLOG] perflog_write_evt_iis sent logtype=2, logid=4, param1=0, pid=0, tid=1449, net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity 2020-04-22 16:40:58.373 1449-3392/? D/StorageManagerService: getExternalStorageMountMode : 3 2020-04-22 16:40:58.373 1449-3392/? D/StorageManagerService: getExternalStorageMountMode : final mountMode=1, uid : 10562, packageName : net.zetetic.sqlcipher.test 2020-04-22 16:40:58.373 1449-3392/? I/ApplicationPolicy: isApplicationExternalStorageWhitelisted:net.zetetic.sqlcipher.test user:0 2020-04-22 16:40:58.374 1449-3392/? D/ApplicationPolicy: isApplicationExternalStorageWhitelisted: DO is not enabled on user 0. Allowed. 2020-04-22 16:40:58.374 1449-3392/? D/ActivityManager: package net.zetetic.sqlcipher.test, user - 0 is SDcard whitelisted 2020-04-22 16:40:58.374 1449-3392/? I/ApplicationPolicy: isApplicationExternalStorageBlacklisted:net.zetetic.sqlcipher.test user:0 2020-04-22 16:40:58.374 1449-3392/? D/ApplicationPolicy: isApplicationExternalStorageBlacklisted: DO is not enabled on user 0. Allowed. 2020-04-22 16:40:58.374 1449-3392/? I/ApplicationPolicy: isApplicationExternalStorageBlacklisted:net.zetetic.sqlcipher.test user:0 2020-04-22 16:40:58.374 1449-3392/? D/ApplicationPolicy: isApplicationExternalStorageBlacklisted: DO is not enabled on user 0. Allowed. 2020-04-22 16:40:58.375 1449-3392/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.375 820-820/? I/Layer: id=35477 onRemoved AppWindowToken{6be0ad8 token=Token{f7e35bb ActivityRecord{2b8824a u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestSuiteBehaviorsActivity t870}}}#0 2020-04-22 16:40:58.376 1449-1572/? D/InputTransport: Input channel constructed: fd=573 2020-04-22 16:40:58.376 1449-1572/? D/InputTransport: Input channel constructed: fd=811 2020-04-22 16:40:58.379 1449-2099/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver 2020-04-22 16:40:58.379 815-27390/? V/APM_AudioPolicyManager: AudioPolicyManager:setRecordSilenced(uid:10562, silenced:1) 2020-04-22 16:40:58.379 952-1923/? I/CameraService: UidPolicy: onUidIdle, uid = 10562 2020-04-22 16:40:58.379 1449-1507/? W/ActivityManager: setHasOverlayUi called on unknown pid: 4388 2020-04-22 16:40:58.379 820-898/? I/SurfaceFlinger: id=35484 createSurf (5120x5120),2 flag=4, 6a02d26 Splash Screen net.zetetic.sqlcipher.test#0 2020-04-22 16:40:58.379 952-1923/? I/CameraService: UidPolicy: No processes running for a uid=10562 disabled=0 2020-04-22 16:40:58.379 952-1923/? I/CameraService: UidPolicy: onUidIdle, uid = 10562 2020-04-22 16:40:58.379 952-1582/? I/CameraService: UidPolicy: onUidIdle, uid = 10562 2020-04-22 16:40:58.379 1802-1802/? D/HidDeviceService: handleMessage(): msg.what=8 2020-04-22 16:40:58.379 1802-1802/? D/HidDeviceService: unregisterAppUid(): uid=10562 2020-04-22 16:40:58.379 1802-1802/? D/HidDeviceService: unregisterAppUid(): caller UID doesn't match user UID 2020-04-22 16:40:58.379 1802-1802/? D/HidDeviceService: handleMessage(): msg.what=8 2020-04-22 16:40:58.379 1802-1802/? D/HidDeviceService: unregisterAppUid(): uid=10562 2020-04-22 16:40:58.380 1802-1802/? D/HidDeviceService: unregisterAppUid(): caller UID doesn't match user UID 2020-04-22 16:40:58.380 1449-1608/? I/HqmInfo::AppCrashAnalyzer: checkAppError: list is null 2020-04-22 16:40:58.380 1449-1495/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver 2020-04-22 16:40:58.380 1449-1495/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver 2020-04-22 16:40:58.380 1449-1495/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver 2020-04-22 16:40:58.381 1449-1572/? D/ViewRootImpl@7915881[test]: setView = DecorView@4c37714[test] TM=true MM=false 2020-04-22 16:40:58.385 1449-1608/? I/HqmInfo::AppCrashAnalyzer: checkAppError: list is null 2020-04-22 16:40:58.389 14439-4531/? D/AppErrorNotification: errorType : 24, process : net.zetetic.sqlcipher.test , uid : 0 2020-04-22 16:40:58.390 14439-4531/? I/SmsApplication: getIncomingUserId() : contextUserId = 0, callingUid = 1000 2020-04-22 16:40:58.393 4533-4533/? E/Zygote: isWhitelistProcess - Process is Whitelisted 2020-04-22 16:40:58.394 1449-1572/? V/WindowManager: Relayout Window{6a02d26 u0 Splash Screen net.zetetic.sqlcipher.test}: viewVisibility=0 req=2560x1600 {(0,0)(fillxfill) sim={adjust=pan} ty=APPLICATION_STARTING wanim=0x10302ff fl=81830118 pfl=1020011 vsysui=10 naviIconColor=0} 2020-04-22 16:40:58.394 4533-4533/? E/Zygote: accessInfo : 1 2020-04-22 16:40:58.395 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.395 1449-1572/? V/WindowManager: Resize reasons for w=Window{6a02d26 u0 Splash Screen net.zetetic.sqlcipher.test}: contentInsetsChanged=false [0,54][0,0] visibleInsetsChanged=false [0,54][0,0] stableInsetsChanged=false [0,54][0,0] outsetsChanged=false [0,0][0,0] surfaceResized=false configChanged=true dragResizingChanged=false reportOrientationChanged=false displayCutoutChanged=false nextForceRelayoutToClient=false 2020-04-22 16:40:58.395 1449-1497/? I/ActivityManager: Start proc 4533:net.zetetic.sqlcipher.test/u0a562 for activity net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity 2020-04-22 16:40:58.396 820-2287/? I/SurfaceFlinger: id=35485 createSurf (2560x1600),1 flag=404, Splash Screen net.zetetic.sqlcipher.test#0 2020-04-22 16:40:58.398 1449-1572/? D/WindowManager: makeSurface duration=2 name=Splash Screen net.zetetic.sqlcipher.test 2020-04-22 16:40:58.398 1449-1572/? D/ViewRootImpl@7915881[test]: Relayout returned: old=[0,0][2560,1600] new=[0,0][2560,1600] result=0x7 surface={true 503718440960} changed=true 2020-04-22 16:40:58.399 1449-1572/? D/ViewRootImpl@7915881[test]: MSG_RESIZED: frame=[0,0][2560,1600] ci=[0,54][0,0] vi=[0,54][0,0] or=2 2020-04-22 16:40:58.401 1449-1818/? I/Telecom:SamsungTelecomServiceImpl: getSystemDialerPackage - callingPid : 14439 / processName : sts 2020-04-22 16:40:58.404 4533-4533/? I/.sqlcipher.tes: Late-enabling -Xcheck:jni 2020-04-22 16:40:58.415 14439-4531/? W/PkgUtils: p: com.google.android.youtube, u:0 2020-04-22 16:40:58.416 1449-1572/? D/WindowManager: finishDrawingWindow: Window{6a02d26 u0 Splash Screen net.zetetic.sqlcipher.test} mDrawState=DRAW_PENDING 2020-04-22 16:40:58.417 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.417 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=Splash Screen net.zetetic.sqlcipher.test)/@0x74e94ac 2020-04-22 16:40:58.423 1449-3413/? D/AccountManagerService: getUserAccounts0 2020-04-22 16:40:58.426 1449-1476/? D/AccountManagerService: getUserAccounts0 2020-04-22 16:40:58.428 1449-1818/? D/ConnectivityService: filterNetworkStateForUid() uid: 10041, pid: 15144 2020-04-22 16:40:58.428 1449-1818/? D/ConnectivityService: isNetworkWithLinkPropertiesBlocked: uid=10041, vpn=DISCONNECTED,false 2020-04-22 16:40:58.428 1449-1818/? D/ConnectivityService: filterNetworkStateForUid() uid: 10041, pid: 15144, networkInfo: [type: WIFI[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: (none), failover: false, available: true, roaming: false] 2020-04-22 16:40:58.444 1449-2077/? D/AccountManagerService: getUserAccounts0 2020-04-22 16:40:58.449 1449-2099/? D/AccountManagerService: getUserAccounts0 2020-04-22 16:40:58.455 14439-4531/? V/FasUtils: There is net.zetetic.sqlcipher.test [10562] in FAS table 2020-04-22 16:40:58.460 1449-2099/? I/ActivityManager: DSS on for net.zetetic.sqlcipher.test and scale is 1.0 2020-04-22 16:40:58.462 1449-2099/? V/WindowOrientationListener: getProposedRotation :1 2020-04-22 16:40:58.462 1449-2099/? V/WindowManager: rotationForOrientationLw(orient=-1, last=1); user=1 sensorRotation=1 mLidState=-1 mDockMode=0 mHdmiPlugged=false 2020-04-22 16:40:58.462 1449-2099/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.462 1449-2099/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=Splash Screen net.zetetic.sqlcipher.test)/@0x74e94ac 2020-04-22 16:40:58.465 1449-2099/? D/GamePkgDataHelper: notifyAppCreate(), pkgName: net.zetetic.sqlcipher.test, sendRet: true 2020-04-22 16:40:58.465 1449-1779/? D/GamePkgDataHelper: getGamePkgData(). net.zetetic.sqlcipher.test 2020-04-22 16:40:58.465 1449-1779/? D/GameManagerService: handleMessage(), MSG_APP_CREATE. ignore. pkgName: net.zetetic.sqlcipher.test 2020-04-22 16:40:58.465 1449-2099/? D/InputDispatcher: Focused application set to: 1fe2adf 2020-04-22 16:40:58.466 1449-2080/? D/libperflog: [PERFMON] perflog_write_log sock = 454 2020-04-22 16:40:58.466 1449-2080/? D/libperflog: [PERFMON] current_write_len = 122 2020-04-22 16:40:58.466 1449-2080/? D/libperflog: [LIBPERFLOG] perflog_write_log sent logtype=1, pid=0, tid=1449, [ActivityLaunch] [C] net.zetetic.sqlcipher.test 534316 2206056 2046240 1 29 1 33 72799324 2096860 -1 -1 -1 -1 -1 -1 -1 35 2020-04-22 16:40:58.466 1449-2080/? D/libperflog: [LIBPERFLOG] perflog_write_evt sock = 454 2020-04-22 16:40:58.466 1449-2080/? D/libperflog: [LIBPERFLOG] perflog_write_evt_iis sent logtype=2, logid=4, param1=1, pid=0, tid=1449, net.zetetic.sqlcipher.test 2020-04-22 16:40:58.466 1449-2099/? D/GameManagerService: noteResumeComponent(), resumeComponent: net.zetetic.sqlcipher.test 2020-04-22 16:40:58.466 1449-2099/? D/MARsPolicyManager: onPackageResumedFG pkgName = net.zetetic.sqlcipher.test, userId = 0 2020-04-22 16:40:58.466 1449-1779/? D/GameManagerService: handleForegroundChange(). pkgName: net.zetetic.sqlcipher.test, clsName: net.zetetic.activities.TestRunnerSelectionActivity,FgActivityName:net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity,userID:0 2020-04-22 16:40:58.466 1449-1779/? D/GameManagerService: handleForegroundChange(). same package. game has never resumed yet. ignore 2020-04-22 16:40:58.467 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.467 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=Splash Screen net.zetetic.sqlcipher.test)/@0x74e94ac 2020-04-22 16:40:58.468 1449-1572/? V/WindowManager: performShowLocked: mDrawState=HAS_DRAWN in Window{6a02d26 u0 Splash Screen net.zetetic.sqlcipher.test} 2020-04-22 16:40:58.469 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.470 1449-1496/? D/CodecSolution: isMaxAspectPackageEx: net.zetetic.sqlcipher.test : false(0) 2020-04-22 16:40:58.471 952-1582/? I/CameraService: UidPolicy: onUidActive, uid = 10562 2020-04-22 16:40:58.471 1449-1496/? D/WindowManager: setSystemUiVisibility: vis= 0x8018, fullscreenVis= 0x0, dockedVis= 0x0, win=Window{6a02d26 u0 Splash Screen net.zetetic.sqlcipher.test} 2020-04-22 16:40:58.471 815-13262/? V/APM_AudioPolicyManager: AudioPolicyManager:setRecordSilenced(uid:10562, silenced:0) 2020-04-22 16:40:58.471 1802-1802/? D/HidDeviceService: handleMessage(): msg.what=8 2020-04-22 16:40:58.474 14439-4531/? D/BatteryIssueDaoImpl: deleteAnomalyPastSpecificDay 2020-04-22 16:40:58.474 14439-4531/? I/BatteryIssueDaoImpl: set badge_for_dm_battery : 0 2020-04-22 16:40:58.479 1905-1917/? W/JavaBinder: BinderProxy is being destroyed but the application did not call unlinkToDeath to unlink all of its death recipients beforehand. Releasing leaked death recipient: com.android.systemui.qs.external.TileLifecycleManager 2020-04-22 16:40:58.483 820-820/? I/SurfaceFlinger: Display 0 HWC layers: type | handle | flag | format | source crop (l,t,r,b) | frame | name ------------+--------------+------+-----------+----------------------------+---------------------+------ Device | 0x7f0a62dbd0 | 0002 | RGBA_8888 | 0.0 0.0 2560.0 1600.0 | 0 0 2560 1600 | Splash Screen net.zetetic.sqlcipher.test#0 Device | 0x7f0a62e110 | 0000 | RGBA_8888 | 0.0 0.0 2560.0 54.0 | 0 0 2560 54 | StatusBar[1905]#0 Device | 0x7f0a62d2a0 | 0000 | RGBA_8888 | 0.0 0.0 45.0 1600.0 | 2515 0 2560 1600 | MultiWindowGestureDetector[1905]#0 Device | 0x7f0a62dc40 | 0000 | RGBA_8888 | 0.0 0.0 2560.0 335.0 | 0 1265 2560 1600 | NavbarGestureHandler[1905]#0 Device | 0x7f0aa3a630 | 0000 | RGBA_8888 | 0.0 0.0 23.0 1600.0 | 0 0 23 1600 | ScreenDecorOverlay[1905]#0 Device | 0x7f0aa3a710 | 0000 | RGBA_8888 | 0.0 0.0 23.0 1600.0 | 2537 0 2560 1600 | ScreenDecorOverlayBottom[1905]#0 2020-04-22 16:40:58.486 952-1582/? I/CameraService: UidPolicy: onUidActive, uid = 5012 2020-04-22 16:40:58.486 1802-1802/? D/HidDeviceService: handleMessage(): msg.what=8 2020-04-22 16:40:58.487 14439-4531/? W/AppErrorNotification: Skip. net.zetetic.sqlcipher.test is not notiable item 2020-04-22 16:40:58.489 14439-14439/? I/SM_UTILS: smBadge - batteryCnt : 0, malwareApp : 0, aasaCnt 0 2020-04-22 16:40:58.489 14439-14439/? I/SM_UTILS: badge_for_dm_scoreboard badge_count=0 putInt result=true 2020-04-22 16:40:58.490 14439-14439/? I/SM_UTILS: badge_for_dm_battery badge_count=0 putInt result=true 2020-04-22 16:40:58.490 14439-14439/? I/SM_UTILS: badge_for_dm_security badge_count=0 putInt result=true 2020-04-22 16:40:58.494 1802-1802/? D/HidDeviceService: handleMessage(): msg.what=8 2020-04-22 16:40:58.554 4533-4564/net.zetetic.sqlcipher.test D/libEGL: loaded /vendor/lib64/egl/libEGL_adreno.so 2020-04-22 16:40:58.560 4533-4564/net.zetetic.sqlcipher.test D/libEGL: loaded /vendor/lib64/egl/libGLESv1_CM_adreno.so 2020-04-22 16:40:58.574 4533-4564/net.zetetic.sqlcipher.test D/libEGL: loaded /vendor/lib64/egl/libGLESv2_adreno.so 2020-04-22 16:40:58.575 4533-4533/net.zetetic.sqlcipher.test I/MultiWindowDecorSupport: updateCaptionType >> com.android.internal.policy.MultiWindowDecorSupport@33a4851, isFloating: false, isApplication: true, hasWindowDecorCaption: false, hasWindowControllerCallback: true 2020-04-22 16:40:58.575 4533-4533/net.zetetic.sqlcipher.test D/MultiWindowDecorSupport: setCaptionType = 0, DecorView = DecorView@17fa8b6[] 2020-04-22 16:40:58.587 4533-4533/net.zetetic.sqlcipher.test D/OpenGLRenderer: Skia GL Pipeline 2020-04-22 16:40:58.470 1449-1572/? I/chatty: uid=1000(system) android.anim identical 1 line 2020-04-22 16:40:58.477 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.600 1449-2054/? D/MdnieScenarioControlService: packageName : net.zetetic.sqlcipher.test className : net.zetetic.activities.TestRunnerSelectionActivity 2020-04-22 16:40:58.600 1449-2054/? V/MdnieScenarioControlService: setUIMode from UI function(3) 2020-04-22 16:40:58.620 4533-4533/net.zetetic.sqlcipher.test D/EmergencyMode: [EmergencyManager] android createPackageContext successful 2020-04-22 16:40:58.628 1449-2077/? D/InputTransport: Input channel constructed: fd=988 2020-04-22 16:40:58.628 1449-2077/? D/InputTransport: Input channel constructed: fd=998 2020-04-22 16:40:58.629 820-898/? I/SurfaceFlinger: id=35486 createSurf (5120x5120),2 flag=4, 77e714f net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity#0 2020-04-22 16:40:58.630 1449-2077/? D/InputTransport: Input channel destroyed: fd=998 2020-04-22 16:40:58.630 4533-4533/net.zetetic.sqlcipher.test D/InputTransport: Input channel constructed: fd=66 2020-04-22 16:40:58.631 4533-4533/net.zetetic.sqlcipher.test D/ViewRootImpl@7ef034e[TestRunnerSelectionActivity]: setView = DecorView@17fa8b6[TestRunnerSelectionActivity] TM=true MM=false 2020-04-22 16:40:58.645 1449-2077/? V/WindowManager: Relayout Window{77e714f u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity}: viewVisibility=0 req=2560x1600 {(0,0)(fillxfill) sim={adjust=pan} ty=BASE_APPLICATION wanim=0x10302ff fl=81810100 pfl=1020000 vsysui=10 naviIconColor=0} 2020-04-22 16:40:58.646 1449-2077/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.646 1449-2077/? V/WindowManager: Resize reasons for w=Window{77e714f u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity}: contentInsetsChanged=false [0,54][0,0] visibleInsetsChanged=false [0,54][0,0] stableInsetsChanged=false [0,54][0,0] outsetsChanged=false [0,0][0,0] surfaceResized=false configChanged=true dragResizingChanged=false reportOrientationChanged=false displayCutoutChanged=false nextForceRelayoutToClient=false 2020-04-22 16:40:58.648 820-2287/? I/SurfaceFlinger: id=35487 createSurf (2560x1600),1 flag=404, net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity[4533]#0 2020-04-22 16:40:58.648 1449-2077/? D/WindowManager: makeSurface duration=2 name=net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity[4533] 2020-04-22 16:40:58.649 1449-2077/? V/WindowManager: Changing focus from null to Window{77e714f u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity} Callers=com.android.server.wm.WindowManagerService.relayoutWindow:2536 com.android.server.wm.Session.relayoutForTranslate:308 android.view.IWindowSession$Stub.onTransact:518 com.android.server.wm.Session.onTransact:192 2020-04-22 16:40:58.649 1449-2077/? D/StatusBarManagerService: notifyRequestedSystemKey recent=false home=false 2020-04-22 16:40:58.649 1449-2077/? D/InputDispatcher: Focus entered window (4533): 77e714f 0 2020-04-22 16:40:58.650 1449-1507/? D/WindowManager: reportFocusChangedSerialized, focused=true, inTouchMode=true, win=Window{77e714f u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity} 2020-04-22 16:40:58.651 4533-4533/net.zetetic.sqlcipher.test D/ViewRootImpl@7ef034e[TestRunnerSelectionActivity]: Relayout returned: old=[0,0][2560,1600] new=[0,0][2560,1600] result=0x7 surface={true 504733040640} changed=true 2020-04-22 16:40:58.653 4533-4570/net.zetetic.sqlcipher.test I/AdrenoGLES: QUALCOMM build : ab3915a, If703410f3a Build Date : 08/27/19 OpenGL ES Shader Compiler Version: EV031.26.14.00 Local Branch : Remote Branch : refs/tags/AU_LINUX_ANDROID_LA.UM.7.1.R1.09.00.00.461.151 Remote Branch : NONE Reconstruct Branch : NOTHING 2020-04-22 16:40:58.653 4533-4570/net.zetetic.sqlcipher.test I/AdrenoGLES: Build Config : S P 6.0.9 AArch64 2020-04-22 16:40:58.655 4533-4570/net.zetetic.sqlcipher.test I/AdrenoGLES: PFP: 0x016ee185, ME: 0x00000000 2020-04-22 16:40:58.659 582-582/? E/audit: avc: denied { find } for interface=vendor.display.config::IDisplayConfig sid=u:r:hal_configstore_default:s0 pid=770 scontext=u:r:hal_configstore_default:s0 tcontext=u:object_r:hal_display_config_hwservice:s0 tclass=hwservice_manager permissive=0 2020-04-22 16:40:58.659 686-686/? E/audit: type=1400 audit(1587566458.652:12207): avc: denied { getattr } for pid=582 comm="hwservicemanage" name="avc_msg" dev="proc" ino=4026533647 scontext=u:r:hwservicemanager:s0 tcontext=u:object_r:proc_avc_msg:s0 tclass=file permissive=0 SEPF_SM-T865_9_0015 audit_filtered 2020-04-22 16:40:58.659 686-686/? E/audit: type=1300 audit(1587566458.652:12207): arch=c00000b7 syscall=56 success=yes exit=8 a0=ffffff9c a1=792f255880 a2=1 a3=0 items=0 ppid=1 pid=582 auid=4294967295 uid=1000 gid=1000 euid=1000 suid=1000 fsuid=1000 egid=1000 sgid=1000 fsgid=1000 tty=(none) ses=4294967295 comm="hwservicemanage" exe="/system/bin/hwservicemanager" subj=u:r:hwservicemanager:s0 key=(null) 2020-04-22 16:40:58.659 686-686/? E/audit: type=1327 audit(1587566458.652:12207): proctitle="/system/bin/hwservicemanager" 2020-04-22 16:40:58.660 4533-4570/net.zetetic.sqlcipher.test I/ConfigStore: android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasWideColorDisplay retrieved: 1 2020-04-22 16:40:58.660 582-582/? E/audit: avc: denied { find } for interface=vendor.display.config::IDisplayConfig sid=u:r:hal_configstore_default:s0 pid=770 scontext=u:r:hal_configstore_default:s0 tcontext=u:object_r:hal_display_config_hwservice:s0 tclass=hwservice_manager permissive=0 2020-04-22 16:40:58.661 4533-4570/net.zetetic.sqlcipher.test I/ConfigStore: android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 1 2020-04-22 16:40:58.661 686-686/? E/audit: type=1400 audit(1587566458.652:12208): avc: denied { getattr } for pid=582 comm="hwservicemanage" name="avc_msg" dev="proc" ino=4026533647 scontext=u:r:hwservicemanager:s0 tcontext=u:object_r:proc_avc_msg:s0 tclass=file permissive=0 SEPF_SM-T865_9_0015 audit_filtered 2020-04-22 16:40:58.661 686-686/? E/audit: type=1300 audit(1587566458.652:12208): arch=c00000b7 syscall=56 success=yes exit=8 a0=ffffff9c a1=792f255880 a2=1 a3=0 items=0 ppid=1 pid=582 auid=4294967295 uid=1000 gid=1000 euid=1000 suid=1000 fsuid=1000 egid=1000 sgid=1000 fsgid=1000 tty=(none) ses=4294967295 comm="hwservicemanage" exe="/system/bin/hwservicemanager" subj=u:r:hwservicemanager:s0 key=(null) 2020-04-22 16:40:58.661 686-686/? E/audit: type=1327 audit(1587566458.652:12208): proctitle="/system/bin/hwservicemanager" 2020-04-22 16:40:58.661 4533-4570/net.zetetic.sqlcipher.test I/OpenGLRenderer: Initialized EGL, version 1.4 2020-04-22 16:40:58.661 4533-4570/net.zetetic.sqlcipher.test D/OpenGLRenderer: Swap behavior 2 2020-04-22 16:40:58.666 4533-4570/net.zetetic.sqlcipher.test D/OpenGLRenderer: eglCreateWindowSurface = 0x756c190380, 0x75846f0010 2020-04-22 16:40:58.678 1449-2077/? D/WindowManager: finishDrawingWindow: Window{77e714f u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity} mDrawState=DRAW_PENDING 2020-04-22 16:40:58.679 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.679 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity[4533])/@0x97680dc 2020-04-22 16:40:58.679 4533-4533/net.zetetic.sqlcipher.test D/ViewRootImpl@7ef034e[TestRunnerSelectionActivity]: MSG_RESIZED: frame=[0,0][2560,1600] ci=[0,54][0,0] vi=[0,54][0,0] or=2 2020-04-22 16:40:58.679 4533-4533/net.zetetic.sqlcipher.test D/ViewRootImpl@7ef034e[TestRunnerSelectionActivity]: MSG_WINDOW_FOCUS_CHANGED 1 1 2020-04-22 16:40:58.680 4533-4533/net.zetetic.sqlcipher.test D/InputMethodManager: prepareNavigationBarInfo() DecorView@17fa8b6[TestRunnerSelectionActivity] 2020-04-22 16:40:58.680 4533-4533/net.zetetic.sqlcipher.test D/InputMethodManager: getNavigationBarColor() -855310 2020-04-22 16:40:58.681 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.681 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity[4533])/@0x97680dc 2020-04-22 16:40:58.681 1449-1572/? I/WindowManager: Cancelling animation restarting=false, leash=null, surface=Surface(name=77e714f net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity)/@0x8e015e5, parent=Surface(name=AppWindowToken{1fe2adf token=Token{440457e ActivityRecord{5abbb39 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity t870}}})/@0xdb7391d 2020-04-22 16:40:58.681 1449-1572/? V/WindowManager: performShowLocked: mDrawState=HAS_DRAWN in Window{77e714f u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity} 2020-04-22 16:40:58.682 4533-4533/net.zetetic.sqlcipher.test D/InputMethodManager: prepareNavigationBarInfo() DecorView@17fa8b6[TestRunnerSelectionActivity] 2020-04-22 16:40:58.682 4533-4533/net.zetetic.sqlcipher.test D/InputMethodManager: getNavigationBarColor() -855310 2020-04-22 16:40:58.682 4533-4533/net.zetetic.sqlcipher.test V/InputMethodManager: Starting input: tba=net.zetetic.sqlcipher.test ic=null mNaviBarColor -855310 mIsGetNaviBarColorSuccess true , NavVisible : true , NavTrans : false 2020-04-22 16:40:58.682 4533-4533/net.zetetic.sqlcipher.test D/InputMethodManager: startInputInner - Id : 0 2020-04-22 16:40:58.682 4533-4533/net.zetetic.sqlcipher.test I/InputMethodManager: startInputInner - mService.startInputOrWindowGainedFocus 2020-04-22 16:40:58.683 1449-2077/? D/InputMethodManagerService: startInputOrWindowGainedFocus : windowGainedFocus 2020-04-22 16:40:58.683 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.683 1449-2077/? D/InputMethodManagerService: windowGainedFocus: destinationUserId (getCallingUid) = 0 2020-04-22 16:40:58.683 1449-2077/? D/InputMethodManagerService: windowGainedFocus: currentUserId=0 2020-04-22 16:40:58.683 1449-2077/? D/InputMethodManagerService: windowGainedFocus: mCurrentFocusedUserId=0 2020-04-22 16:40:58.683 1449-2077/? D/InputMethodManagerService: windowGainedFocus : lock - currentUserId = 0 destinationUserId = 0 2020-04-22 16:40:58.683 1449-2077/? D/InputMethodManagerService: windowGainedFocus : mCurrentFocusedUserId - 0 and mSecureKeypadEnabled - false, userSwitched - false 2020-04-22 16:40:58.684 1449-2077/? V/InputMethodManagerService: windowGainedFocus : reason=WINDOW_FOCUS_GAIN client=android.os.BinderProxy@fa6a029 inputContext=null missingMethods= attribute=android.view.inputmethod.EditorInfo@23348ba nm = net.zetetic.sqlcipher.test controlFlags=#104 softInputMode=#20 windowFlags=#81810100 2020-04-22 16:40:58.684 1449-1572/? D/ViewRootImpl@7915881[test]: dispatchDetachedFromWindow 2020-04-22 16:40:58.684 1449-2077/? V/InputMethodManagerService: hideCurrentInputLocked : shouldHideSoftInput is false 2020-04-22 16:40:58.684 1449-1572/? V/WindowManager: Remove Window{6a02d26 u0 Splash Screen net.zetetic.sqlcipher.test}: mSurfaceController=Surface(name=Splash Screen net.zetetic.sqlcipher.test)/@0x74e94ac mAnimatingExit=false mRemoveOnExit=false mHasSurface=true surfaceShowing=true isAnimationSet=false app-animation=false mWillReplaceWindow=false inPendingTransaction=false mDisplayFrozen=false callers=com.android.server.wm.WindowState.removeIfPossible:2803 com.android.server.wm.WindowManagerService.removeWindow:1954 com.android.server.wm.Session.remove:274 android.view.ViewRootImpl.dispatchDetachedFromWindow:4651 android.view.ViewRootImpl.doDie:8119 android.view.ViewRootImpl$ViewRootHandler.handleMessage:5083 2020-04-22 16:40:58.684 1449-1507/? I/ActivityManager: Displayed net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity: +311ms 2020-04-22 16:40:58.684 1449-1507/? I/Pageboost: Launch time gathered : pid 4533 net.zetetic.sqlcipher.test 311 2020-04-22 16:40:58.684 1449-1572/? I/WindowManager: Cancelling animation restarting=true, leash=null, surface=Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61, parent=Surface(name=AppWindowToken{1fe2adf token=Token{440457e ActivityRecord{5abbb39 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity t870}}})/@0xdb7391d 2020-04-22 16:40:58.684 1449-1572/? I/WindowManager: Reparenting to leash, surface=Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61 2020-04-22 16:40:58.685 2611-2611/? E/pageboostd: Received HALT command code 2 2020-04-22 16:40:58.685 1449-2080/? D/libperflog: [PERFMON] perflog_write_log sock = 454 2020-04-22 16:40:58.685 1449-2080/? D/libperflog: [PERFMON] current_write_len = 179 2020-04-22 16:40:58.685 1449-2080/? D/libperflog: [LIBPERFLOG] perflog_write_log sent logtype=1, pid=0, tid=1449, [ActivityLaunch] [E] net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity [311] 522648 2206104 2046240 1 28 1 34 72799324 2096860 -1 -1 -1 -1 -1 -1 -1 35 2020-04-22 16:40:58.685 820-898/? I/SurfaceFlinger: id=35488 createSurf (5120x5120),2 flag=4, Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61 - animation-leash#0 2020-04-22 16:40:58.686 1449-1507/? D/CustomFrequencyManagerService: releaseDVFSLockLocked : Getting Lock type frm List : DVFS_MIN_LIMIT frequency : 1612800 uid : 1000 pid : 1449 tag : AMS_ACT_RESUME@CPU_MIN@72 2020-04-22 16:40:58.686 1449-1507/? D/ActivityManagerPerformance: AMP_release() ACT_RESUME 2020-04-22 16:40:58.686 1449-2080/? D/libperflog: [LIBPERFLOG] perflog_write_evt sock = 454 2020-04-22 16:40:58.686 1449-2080/? D/libperflog: [LIBPERFLOG] perflog_write_evt_iis sent logtype=2, logid=4, param1=2, pid=0, tid=1449, net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity [311] 2020-04-22 16:40:58.687 1449-1572/? D/WindowManager: makeSurface duration=3 leash=Surface(name=Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61 - animation-leash)/@0xd3d3686 2020-04-22 16:40:58.687 1449-1572/? D/SurfaceControl: reparent is called, sc=Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61, newParentHandle=android.os.BinderProxy@b513b47, transaction=android.view.SurfaceControl$Transaction@1900f74 2020-04-22 16:40:58.688 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.689 820-898/? D/Layer: [6a02d26 Splash Screen net.zetetic.sqlcipher.test#0]::reparent oldParent:[AppWindowToken{1fe2adf token=Token{440457e ActivityRecord{5abbb39 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity t870}}}#0], newParent:[Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61 - animation-leash#0] 2020-04-22 16:40:58.689 1449-1572/? D/InputTransport: Input channel destroyed: fd=573 2020-04-22 16:40:58.689 1449-1572/? D/InputTransport: Input channel destroyed: fd=811 2020-04-22 16:40:58.689 1449-2077/? V/InputMethodManagerService: Creating new session for client ClientState{6c41c9d uid 10562 pid 4533} 2020-04-22 16:40:58.690 1449-2077/? D/InputTransport: Input channel constructed: fd=573 2020-04-22 16:40:58.690 1449-2077/? D/InputTransport: Input channel constructed: fd=811 2020-04-22 16:40:58.690 1449-2077/? D/InputTransport: Input channel destroyed: fd=811 2020-04-22 16:40:58.690 16321-6120/? D/InputTransport: Input channel constructed: fd=68 2020-04-22 16:40:58.692 1449-2077/? D/InputMethodManagerService: checkDisplayOfStartInputAndUpdateKeyboard display Id 0last 0 2020-04-22 16:40:58.692 1449-2077/? D/InputMethodManagerService: setCurrentInputInfo - inputContext : null eidtorInfo : android.view.inputmethod.EditorInfo@23348ba missingMethods : 0 2020-04-22 16:40:58.692 1449-2077/? D/InputTransport: Input channel constructed: fd=811 2020-04-22 16:40:58.692 1449-2077/? D/InputTransport: Input channel destroyed: fd=811 2020-04-22 16:40:58.692 4533-4552/net.zetetic.sqlcipher.test D/InputTransport: Input channel constructed: fd=72 2020-04-22 16:40:58.693 4533-4533/net.zetetic.sqlcipher.test D/InputMethodManager: prepareNavigationBarInfo() DecorView@17fa8b6[TestRunnerSelectionActivity] 2020-04-22 16:40:58.693 4533-4533/net.zetetic.sqlcipher.test D/InputMethodManager: getNavigationBarColor() -855310 2020-04-22 16:40:58.694 4533-4533/net.zetetic.sqlcipher.test V/InputMethodManager: Starting input: tba=net.zetetic.sqlcipher.test ic=null mNaviBarColor -855310 mIsGetNaviBarColorSuccess true , NavVisible : true , NavTrans : false 2020-04-22 16:40:58.694 4533-4533/net.zetetic.sqlcipher.test D/InputMethodManager: startInputInner - Id : 0 2020-04-22 16:40:58.694 16321-16321/? I/InputMethodWrapper: call dispatchStartInputWithToken 2020-04-22 16:40:58.695 16321-16321/? I/InputMethodService: dispatchStartInputWithToken 2020-04-22 16:40:58.696 16321-16321/? I/SKBD_Performance: [IIManager] [PF_CL][onFinishInput] 1523698 2020-04-22 16:40:58.697 16321-16321/? D/InputMethodService: checkDisplayOfStartInputAndUpdateKeyboard display Id 0last 0 2020-04-22 16:40:58.698 16321-16321/? I/SKBD: SamsungKeypad [IMI] onStartInput - caller pid : 4533, caller uid : 10562 2020-04-22 16:40:58.698 16321-16321/? I/SKBD: SamsungKeypad ConnectionUtils.getNetworkStateValue() = 2 2020-04-22 16:40:58.731 820-820/? I/SurfaceFlinger: Display 0 HWC layers: type | handle | flag | format | source crop (l,t,r,b) | frame | name ------------+--------------+------+-----------+----------------------------+---------------------+------ Device | 0x7f0a62dd20 | 0002 | RGBA_8888 | 0.0 0.0 2560.0 1600.0 | 0 0 2560 1600 | net.zetetic.sqlcipher.test/net.zetet[...].TestRunnerSelectionActivity[4533]#0 Device | 0x7f0a62dbd0 | 0002 | RGBA_8888 | 0.0 0.0 2560.0 1600.0 | 0 0 2560 1600 | Splash Screen net.zetetic.sqlcipher.test#0 Device | 0x7f0a62e110 | 0000 | RGBA_8888 | 0.0 0.0 2560.0 54.0 | 0 0 2560 54 | StatusBar[1905]#0 Device | 0x7f0a62d2a0 | 0000 | RGBA_8888 | 0.0 0.0 45.0 1600.0 | 2515 0 2560 1600 | MultiWindowGestureDetector[1905]#0 Device | 0x7f0a62dc40 | 0000 | RGBA_8888 | 0.0 0.0 2560.0 335.0 | 0 1265 2560 1600 | NavbarGestureHandler[1905]#0 Device | 0x7f0aa3a630 | 0000 | RGBA_8888 | 0.0 0.0 23.0 1600.0 | 0 0 23 1 2020-04-22 16:40:58.731 820-820/? I/SurfaceFlinger: 600 | ScreenDecorOverlay[1905]#0 Device | 0x7f0aa3a710 | 0000 | RGBA_8888 | 0.0 0.0 23.0 1600.0 | 2537 0 2560 1600 | ScreenDecorOverlayBottom[1905]#0 2020-04-22 16:40:58.692 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.859 1449-1572/? I/WindowManager: reset, Reparenting to original parent, shouldDeferScheduleAnimation=false, destroyLeash=true, surface=Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61, parent=Surface(name=AppWindowToken{1fe2adf token=Token{440457e ActivityRecord{5abbb39 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity t870}}})/@0xdb7391d, parentNativeHandle=android.os.BinderProxy@5d0c3cb, caller=com.android.server.wm.SurfaceAnimator.lambda$getFinishedCallback$0:97 com.android.server.wm.-$$Lambda$SurfaceAnimator$SIBia0mND666K8lMCPsoid8pUTI.run:4 com.android.server.wm.SurfaceAnimator.lambda$getFinishedCallback$1:104 com.android.server.wm.-$$Lambda$SurfaceAnimator$vdRZk66hQVbQCvVXEaQCT1kVmFc.onAnimationFinished:4 com.android.server.wm.LocalAnimationAdapter.lambda$startAnimation$0:74 2020-04-22 16:40:58.859 1449-1572/? D/SurfaceControl: reparent is called, sc=Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61, newParentHandle=android.os.BinderProxy@5d0c3cb, transaction=android.view.SurfaceControl$Transaction@1900f74 2020-04-22 16:40:58.859 1449-1572/? D/SurfaceControl: destroy(transaction), animation-leash is destroyed, sc=Surface(name=Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61 - animation-leash)/@0xd3d3686, transaction=android.view.SurfaceControl$Transaction@1900f74, caller=com.android.server.wm.SurfaceAnimator.reset:344 com.android.server.wm.SurfaceAnimator.lambda$getFinishedCallback$0:97 com.android.server.wm.-$$Lambda$SurfaceAnimator$SIBia0mND666K8lMCPsoid8pUTI.run:4 com.android.server.wm.SurfaceAnimator.lambda$getFinishedCallback$1:104 com.android.server.wm.-$$Lambda$SurfaceAnimator$vdRZk66hQVbQCvVXEaQCT1kVmFc.onAnimationFinished:4 com.android.server.wm.LocalAnimationAdapter.lambda$startAnimation$0:74 com.android.server.wm.-$$Lambda$LocalAnimationAdapter$X--EomqUvw4qy89IeeTFTH7aCMo.run:4 2020-04-22 16:40:58.860 1449-1572/? E/WindowManager: win=Window{6a02d26 u0 Splash Screen net.zetetic.sqlcipher.test EXITING} destroySurfaces: appStopped=false win.mWindowRemovalAllowed=true win.mRemoveOnExit=true win.mViewVisibility=0 caller=com.android.server.wm.AppWindowToken.destroySurfaces:896 com.android.server.wm.AppWindowToken.destroySurfaces:877 com.android.server.wm.WindowState.onExitAnimationDone:5496 com.android.server.wm.WindowStateAnimator.onAnimationFinished:320 com.android.server.wm.WindowState.onAnimationFinished:5920 com.android.server.wm.-$$Lambda$yVRF8YoeNdTa8GR1wDStVsHu8xM.run:2 com.android.server.wm.SurfaceAnimator.lambda$getFinishedCallback$0:100 2020-04-22 16:40:58.860 1449-1572/? I/WindowManager: Destroying surface Surface(name=Splash Screen net.zetetic.sqlcipher.test)/@0x74e94ac called by com.android.server.wm.WindowStateAnimator.destroySurface:1884 com.android.server.wm.WindowStateAnimator.destroySurfaceLocked:821 com.android.server.wm.WindowState.destroySurfaceUnchecked:3882 com.android.server.wm.WindowState.destroySurface:3863 com.android.server.wm.AppWindowToken.destroySurfaces:896 com.android.server.wm.AppWindowToken.destroySurfaces:877 com.android.server.wm.WindowState.onExitAnimationDone:5496 com.android.server.wm.WindowStateAnimator.onAnimationFinished:320 2020-04-22 16:40:58.860 820-1770/? I/SurfaceFlinger: id=35485 Removed Splash Screen net.zetetic.sqlcipher.test#0 (0/58) 2020-04-22 16:40:58.861 1449-1572/? D/WindowManager: clearSavedLeashOnCommit, savedLeash=Surface(name=Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61 - animation-leash)/@0xd3d3686, caller=com.android.server.wm.WindowContainer.removeImmediately:329 com.android.server.wm.WindowState.removeImmediately:2715 com.android.server.wm.WindowState.destroySurface:3866 com.android.server.wm.AppWindowToken.destroySurfaces:896 com.android.server.wm.AppWindowToken.destroySurfaces:877 com.android.server.wm.WindowState.onExitAnimationDone:5496 2020-04-22 16:40:58.863 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:58.865 820-1770/? W/SurfaceFlinger: Attempting to set client state on removed layer: Splash Screen net.zetetic.sqlcipher.test#0 [s.what : 40] 2020-04-22 16:40:58.865 820-1770/? D/Layer: [6a02d26 Splash Screen net.zetetic.sqlcipher.test#0]::reparent oldParent:[Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61 - animation-leash#0], newParent:[AppWindowToken{1fe2adf token=Token{440457e ActivityRecord{5abbb39 u0 net.zetetic.sqlcipher.test/net.zetetic.activities.TestRunnerSelectionActivity t870}}}#0] 2020-04-22 16:40:58.865 820-1770/? W/SurfaceFlinger: Attempting to destroy on removed layer: Splash Screen net.zetetic.sqlcipher.test#0 2020-04-22 16:40:58.865 820-1770/? I/SurfaceFlinger: id=35488 Removed Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61 - animation-leash#0 (2/57) 2020-04-22 16:40:58.865 820-1770/? I/SurfaceFlinger: id=35484 Removed 6a02d26 Splash Screen net.zetetic.sqlcipher.test#0 (1/56) 2020-04-22 16:40:58.874 820-820/? I/Layer: id=35485 onRemoved Splash Screen net.zetetic.sqlcipher.test#0 2020-04-22 16:40:58.875 820-820/? I/Layer: id=35484 onRemoved 6a02d26 Splash Screen net.zetetic.sqlcipher.test#0 2020-04-22 16:40:58.875 820-820/? I/Layer: id=35488 onRemoved Surface(name=6a02d26 Splash Screen net.zetetic.sqlcipher.test)/@0x1812b61 - animation-leash#0 2020-04-22 16:40:58.880 820-820/? I/SurfaceFlinger: Display 0 HWC layers: type | handle | flag | format | source crop (l,t,r,b) | frame | name ------------+--------------+------+-----------+----------------------------+---------------------+------ Device | 0x7f0a62dd20 | 0002 | RGBA_8888 | 0.0 0.0 2560.0 1600.0 | 0 0 2560 1600 | net.zetetic.sqlcipher.test/net.zetet[...].TestRunnerSelectionActivity[4533]#0 Device | 0x7f0a62e110 | 0000 | RGBA_8888 | 0.0 0.0 2560.0 54.0 | 0 0 2560 54 | StatusBar[1905]#0 Device | 0x7f0a62d2a0 | 0000 | RGBA_8888 | 0.0 0.0 45.0 1600.0 | 2515 0 2560 1600 | MultiWindowGestureDetector[1905]#0 Device | 0x7f0a62dc40 | 0000 | RGBA_8888 | 0.0 0.0 2560.0 335.0 | 0 1265 2560 1600 | NavbarGestureHandler[1905]#0 Device | 0x7f0aa3a630 | 0000 | RGBA_8888 | 0.0 0.0 23.0 1600.0 | 0 0 23 1600 | ScreenDecorOverlay[1905]#0 Device | 0x7f0aa3a710 | 0000 | RGBA_8888 | 0.0 0.0 23.0 1600.0 | 2537 0 2560 1600 | ScreenDeco 2020-04-22 16:40:58.880 820-820/? I/SurfaceFlinger: rOverlayBottom[1905]#0 2020-04-22 16:40:59.410 779-3955/? I/sensors-hal: handle_sns_std_sensor_event:92, [SSC_LIGHT] ambient_light lux: 24, ts=717624498748745 2020-04-22 16:40:59.858 1449-1659/? D/WifiStateMachine: enter getWifiLinkLayerStats 2020-04-22 16:40:59.859 1449-1659/? I/WifiVendorHal: getWifiLinkLayerStats(l.3130) before calling iface.getLinkLayerStats 2020-04-22 16:40:58.876 1449-1572/? I/WindowManager: commitFinishDrawingLocked: mDrawState=READY_TO_SHOW Surface(name=NavigationBar[1905])/@0xd7bd187 2020-04-22 16:40:59.862 1449-2099/? D/CustomFrequencyManagerService: FrequencyrequestList.getNextSchedTuneBoostRequest, index: 0 2020-04-22 16:40:59.868 1449-1659/? I/WifiVendorHal: getWifiLinkLayerStats(l.3130) after calling iface.getLinkLayerStats 2020-04-22 16:41:00.000 1449-1611/? D/SamsungAlarmManager: Expired : 8 2020-04-22 16:41:00.001 1449-1611/? V/SamsungAlarmManager: Sending to uid : 1000 action=android.intent.action.TIME_TICK alarm=Alarm{54ae599 type 3 when 717625095 android} 2020-04-22 16:41:00.005 1449-1611/? V/SamsungAlarmManager: Sending to uid : 10100 action=com.samsung.action.EVERY_MINUTE_CLOCK_UPDATE alarm=Alarm{a844c3f type 1 when 1587566460000 com.sec.android.app.launcher} 2020-04-22 16:41:00.007 1449-1611/? I/SamsungAlarmManager: setLocked to kernel - T:2 / 20200422T164107, SetElapsed=717632455, nowELAPSED=717625102 2020-04-22 16:41:00.008 1449-1449/? D/SamsungAlarmManager: setExact Intent (T:3/F:1/AC:false) 20200422T164200 - CU:1000/CP:1449 2020-04-22 16:41:00.008 1449-1449/? I/SamsungAlarmManager: setLocked to kernel - T:2 / 20200422T164107, SetElapsed=717632455, nowELAPSED=717625103 2020-04-22 16:41:00.008 15894-15894/? I/LiveIconManager: onReceive: com.samsung.action.EVERY_MINUTE_CLOCK_UPDATE 2020-04-22 16:41:00.012 1905-1905/? D/KeyguardUpdateMonitor: handleTimeUpdate 2020-04-22 16:41:00.015 1449-2099/? D/SamsungAlarmManager: setInexact Intent (T:1/F:0/AC:false) 20200422T164200 - CU:10100/CP:15894 2020-04-22 16:41:00.015 1449-2099/? I/SamsungAlarmManager: setLocked to kernel - T:2 / 20200422T164107, SetElapsed=717632455, nowELAPSED=717625110 2020-04-22 16:41:00.022 1905-1905/? D/QSClockBellTower: He is ready to ring the bell. (((timeText:16:41, skipUpdateForDemo:false, dateText:Wed, April 22))) 2020-04-22 16:41:00.024 1905-1905/? D/QSClock: status_bar_clock notifyTimeChanged(currentTime:16:41) mClockVisibleByPolicy:true, mClockVisibleByUser:true, visible?true, parent:android.widget.LinearLayout{e8329a7 V.E...... ......ID 0,0-93,54 #7f0a030a app:id/left_clock_container} 2020-04-22 16:41:00.026 1905-1905/? D/QSClockBellTower: Everyone heard the bell. run(currentTime:1587566460021, getTime():Wed Apr 22 16:41:00 GMT+02:00 2020, getTimeZone():libcore.util.ZoneInfo[id="Europe/Rome",mRawOffset=3600000,mEarliestRawOffset=3600000,mUseDst=true,mDstSavings=3600000,transitions=171]) 2020-04-22 16:41:00.033 1905-1905/? D/QSContainerImpl: onMeasure isLandscape? true getDisplayHeight()= 1600 2020-04-22 16:41:00.038 1905-1905/? I/StackScroller: ADJUST STACK.TRANSLATION 2020-04-22 16:41:00.038 1905-1905/? I/chatty: uid=10020(com.samsung.desktopsystemui) com.android.systemui identical 1 line 2020-04-22 16:41:00.038 1905-1905/? I/StackScroller: ADJUST STACK.TRANSLATION 2020-04-22 16:41:00.059 815-13262/? V/APM_AudioPolicyManager: getAudioPolicyConfig: audioParam;outDevice 2020-04-22 16:41:00.059 815-13262/? V/APM_AudioPolicyManager: getNewOutputDevice() selected device 0 2020-04-22 16:41:00.059 815-13262/? V/APM_AudioPolicyManager: ### curdevice : 2 2020-04-22 16:41:00.061 1905-1905/? I/StackScroller: ADJUST STACK.TRANSLATION 2020-04-22 16:41:00.325 1449-1449/? D/Telecom:SamsungPrebindingServiceImpl: handleMessage 0 2020-04-22 16:41:00.326 1449-1449/? I/Telecom:SamsungPrebindingServiceImpl: isSktTPhoneMode supportTPhone: false 2020-04-22 16:41:00.326 1449-1449/? I/Telecom:SamsungPrebindingServiceImpl: needToKeepBinding - false - match not found 2020-04-22 16:41:00.062 1905-1905/? I/chatty: uid=10020(com.samsung.desktopsystemui) com.android.systemui identical 1 line 2020-04-22 16:41:00.062 1905-1905/? I/StackScroller: ADJUST STACK.TRANSLATION 2020-04-22 16:41:00.610 779-3955/? I/sensors-hal: handle_sns_std_sensor_event:92, [SSC_LIGHT] ambient_light lux: 25, ts=717625698744058 2020-04-22 16:41:00.829 1449-1761/? D/PowerManagerService: handleSmartStay(preTest) 2020-04-22 16:41:00.830 1449-1761/? E/SmartFaceManager: checkForSmartStay S 2020-04-22 16:41:00.834 16932-11428/? E/SmartFaceService: setValue, key: empty key for ping value: empty value 2020-04-22 16:41:00.834 16932-11428/? E/SmartFaceService: Client not found. for command: empty key for ping 2020-04-22 16:41:00.835 1449-1761/? D/SmartFaceManager: Sending smart-stay-framecount-reset: to service 2020-04-22 16:41:00.835 16932-11428/? E/SmartFaceService: setValue, key: smart-stay-framecount-reset value: 2020-04-22 16:41:00.836 16932-11428/? E/SmartFaceService: setValue, key: empty key for ping value: empty value 2020-04-22 16:41:00.836 16932-11428/? E/SmartFaceService: Client not found. for command: empty key for ping 2020-04-22 16:41:00.837 16932-11428/? E/SmartFaceService: register:284045379 2020-04-22 16:41:00.841 16932-11428/? E/SmartFaceService: register, serviceType(100) pid 1449 : system 2020-04-22 16:41:00.845 16932-11428/? E/SmartFaceService.Client: New SmartFaceService.Client, service type: 100 pid: 1449 appName: system 2020-04-22 16:41:00.845 16932-11428/? D/Stay/Rotation Worker: client added 2020-04-22 16:41:00.845 16932-11428/? D/Stay/Rotation Worker: updateClientsDone. def. do nothing. 2020-04-22 16:41:00.845 16932-11428/? E/SmartFaceService: Service Type to Worker: 100 2020-04-22 16:41:00.845 16932-11428/? E/SmartFaceService: Last Active clients:0 Current Active clients: 1 2020-04-22 16:41:00.845 16932-11428/? E/SmartFaceService: We have active clients. open camera 2020-04-22 16:41:00.850 16932-11428/? E/SmartFaceService: mWakeLock.acquire() in FD 2020-04-22 16:41:00.850 16932-11428/? D/SmartFaceIndicator: no icon 2020-04-22 16:41:00.856 16932-11428/? E/CameraController: openCamera S 2020-04-22 16:41:00.856 16932-11428/? E/CameraController: sendMessageAndWait, lock.lock before 2020-04-22 16:41:00.856 16932-11428/? E/CameraController: sendMessageAndWait, lock.lock after 2020-04-22 16:41:00.856 16932-11428/? E/CameraController: Wait for 1 2020-04-22 16:41:00.856 16932-11428/? E/CameraController: waitHere, condition var. will be used. related lock release and re-acquired after await done. 2020-04-22 16:41:00.856 16932-16948/? E/CameraV2Controller: handleMessage(1) before lock{ when=0 what=1 arg1=4 target=com.samsung.android.smartface.CameraV2Controller$EventHandler } 2020-04-22 16:41:00.856 16932-16948/? E/CameraV2Controller: handleMessage, lock.lock after 2020-04-22 16:41:00.856 16932-16948/? E/CameraV2Controller: handleMessage(1) { when=-1ms what=1 arg1=4 target=com.samsung.android.smartface.CameraV2Controller$EventHandler } 2020-04-22 16:41:00.857 1449-2099/? D/RestrictionPolicy: isCameraEnabled ret(true) userId(0) cxtInfo.mCallerUid(1000) cxtInfo.mContainerId(0) 2020-04-22 16:41:00.857 16932-16948/? E/CameraController: isCameraAllowed: true 2020-04-22 16:41:00.862 952-1582/? I/CameraService: CameraService::connect call (PID -1 "com.samsung.android.smartface", camera ID 1) for HAL version default and Camera API version 2 2020-04-22 16:41:00.863 952-1582/? I/CameraService: UidPolicy: isUidActive, uid = 1000 2020-04-22 16:41:00.864 1449-2099/? I/EDMNativeHelperService: isCameraEnabled 2020-04-22 16:41:00.865 1449-2099/? D/RestrictionPolicy: isCameraEnabled ret(true) userId(0) cxtInfo.mCallerUid(1000) cxtInfo.mContainerId(0) 2020-04-22 16:41:00.865 952-1582/? E/CameraService: checkTemperature (0) 2020-04-22 16:41:00.865 952-1582/? I/CameraService: CameraService::validateClientPermissionsLocked is ok : calling pid 16932, calling uid 1000, client com.samsung.android.smartface , cameraservice pid=952, device user 0, currently allowed device users: 0 2020-04-22 16:41:00.865 952-1582/? I/CameraService: CameraService::handleEvictionsLocked 2020-04-22 16:41:00.866 952-1582/? I/CameraService: this package is smartface service. 2020-04-22 16:41:00.866 952-1582/? I/CameraService: wouldEvictLocked:getCurrentCostLocked()=0, cost=100, totalCost=100, MaxCost=100 2020-04-22 16:41:00.866 952-1582/? I/CameraService: wouldEvictLocked: owner=16932, priority-score=990, highestPriorityOwner=16932, highestPriority-score=990 2020-04-22 16:41:00.866 952-1582/? I/CameraFlashlight: prepareDeviceOpen: prepare for device open(cameraId=1, package=com.samsung.android.smartface) 2020-04-22 16:41:00.867 952-1582/? I/CameraFlashlight: Skip prepareDeviceOpen for smart stay 2020-04-22 16:41:00.867 952-1582/? E/CameraService: is Need DisableCC 2020-04-22 16:41:00.867 952-1582/? E/CameraService: setCompanionDisableSysFs : cc.disable property set to 1 2020-04-22 16:41:00.870 952-1582/? I/Camera2ClientBase: Camera 1: Opened. Client: com.samsung.android.smartface (PID 16932, UID 1000) 2020-04-22 16:41:00.872 952-1582/? I/CameraDeviceClient: CameraDeviceClient 1: Opened 2020-04-22 16:41:00.874 952-1582/? I/CameraService: startCameraOps: Start camera ops, package name = com.samsung.android.smartface, client UID = 1000 2020-04-22 16:41:00.879 3223-16070/? I/CameraManagerGlobal: postSingleUpdate device: camera id 1 status STATUS_NOT_AVAILABLE 2020-04-22 16:41:00.880 3223-3223/? D/SDHMS:g: writeSysfs:: path = /sys/devices/system/cpu/cpu0/cpufreq/schedutil/down_rate_limit_us, value = 0 2020-04-22 16:41:00.880 3223-3223/? D/SDHMS:g: writeSysfs:: path = /sys/devices/system/cpu/cpu4/cpufreq/schedutil/down_rate_limit_us, value = 0 2020-04-22 16:41:00.881 952-1582/? D/Camera3-Device: initialize: mInterfaceLock.lock() 2020-04-22 16:41:00.881 3223-3223/? D/SDHMS:g: writeSysfs:: path = /sys/devices/system/cpu/cpu7/cpufreq/schedutil/down_rate_limit_us, value = 0 2020-04-22 16:41:00.881 789-12260/? I/CamComm1.0-CamModule: [CAMKPI] camera_module->open : E 2020-04-22 16:41:00.881 789-12260/? I/CHI: [SS_INFO]: [SS_LOG_LEVEL]: 1, [DEVELOPER_LOG_LEVEL]: 0 2020-04-22 16:41:00.885 786-839/? E/ANDR-PERF-MPCTL: Invalid profile no. 0, total profiles 0 only 2020-04-22 16:41:00.888 789-12260/? I/CamComm1.0-CamModule: [CAMKPI] camera_module->open : X 2020-04-22 16:41:00.890 952-1582/? D/Camera3-Device: initialize: mInterfaceLock.unlock() 2020-04-22 16:41:00.891 952-1582/? E/Camera2-FrameProcessorBase: FrameProcessorBase: created 2020-04-22 16:41:00.891 952-1582/? E/Camera2-FrameProcessorBase: registerListener: Registering listener for frame id range 0 - 2147483647 2020-04-22 16:41:00.891 952-1582/? I/CameraService: CameraService::finishConnectLocked E 2020-04-22 16:41:00.891 952-1582/? I/CameraService: wouldEvictLocked:getCurrentCostLocked()=0, cost=100, totalCost=100, MaxCost=100 2020-04-22 16:41:00.891 952-1582/? I/CameraService: wouldEvictLocked: owner=16932, priority-score=990, highestPriorityOwner=16932, highestPriority-score=990 2020-04-22 16:41:00.891 952-1582/? I/CameraService: addAndEvict: push the client(owner 16932, priorityScore 990, state 9) to mClients 2020-04-22 16:41:00.891 952-1582/? I/CameraService: CameraService::finishConnectLocked X 2020-04-22 16:41:00.892 16932-16949/? E/CameraV2Controller: onOpened 2020-04-22 16:41:00.893 16932-16948/? E/CameraV2Controller: Preview size: 320x240 2020-04-22 16:41:00.894 952-1582/? D/Camera3-Device: waitUntilDrained: mInterfaceLock.unlock() 2020-04-22 16:41:00.895 952-1582/? D/CameraDeviceClient: createStream E 2020-04-22 16:41:00.895 952-1582/? D/CameraDeviceClient: createSurfaceFromGbp E: isStreamInfoValid=0, option=0 2020-04-22 16:41:00.895 952-1582/? D/CameraDeviceClient: createSurfaceFromGbp: consumer usage flag: 3, useAsync: 0 2020-04-22 16:41:00.895 952-1582/? D/CameraDeviceClient: createSurfaceFromGbp: width=320, height=240, format=35, dataSpace=146931712 2020-04-22 16:41:00.895 952-1582/? D/CameraDeviceClient: roundBufferDimensionNearest inWidth=320 inHeight=240, outWidth=320 outHeight=240 X 2020-04-22 16:41:00.895 952-1582/? D/CameraDeviceClient: createSurfaceFromGbp X 2020-04-22 16:41:00.895 952-1582/? D/Camera3-Device: createStream: mInterfaceLock.lock() 2020-04-22 16:41:00.895 952-1582/? D/Camera3-Device: Camera 1: Creating new stream 0: 320 x 240, format 35, dataspace 146931712 rotation 0 consumer usage 0, isShared 0, physicalCameraId 2020-04-22 16:41:00.895 952-1582/? D/Camera3-Device: createStream: mInterfaceLock.unlock() 2020-04-22 16:41:00.895 952-1582/? D/CameraDeviceClient: createStream: Camera 1: Successfully created a new stream ID 0 for output surface (320 x 240) with format 0x23. 2020-04-22 16:41:00.895 952-1582/? D/Camera3-Device: setStreamTransform: mInterfaceLock.lock() 2020-04-22 16:41:00.895 952-1582/? D/Camera3-OutputStream: setTransformLocked: option(0), old transform(0), new transform(13) 2020-04-22 16:41:00.895 952-1582/? D/Camera3-Device: setStreamTransform: mInterfaceLock.unlock() 2020-04-22 16:41:00.895 952-1582/? D/CameraDeviceClient: createStream X 2020-04-22 16:41:00.895 952-1582/? D/Camera3-Device: filterParamsAndConfigureLocked : operatingMode=0 2020-04-22 16:41:00.895 952-1582/? D/Camera3-Device: configureStreamsLocked: Camera 1: Starting stream configuration 2020-04-22 16:41:00.896 789-12260/? I/SecCamDevSession@3.4-impl: [CAMKPI] camera3->configure_streams : E 2020-04-22 16:41:00.898 786-839/? E/ANDR-PERF-MPCTL: Invalid profile no. 0, total profiles 0 only 2020-04-22 16:41:00.904 789-4577/? E/CamX: [ERROR][SENSOR ] camxsensornode.cpp:5682 GetSensorModuleIndexes() GetDeviceIndex failure for Flash, no flash hardware found 2020-04-22 16:41:00.906 789-4577/? I/SS_3A: INFO: AEC: AECAlgoSetNodeInterface: 650: AEC vendorTagBase 0x80530000 2020-04-22 16:41:00.906 789-4577/? I/SS_3A: INFO: AEC: AECAlgoSetNodeInterface: 660: AWB vendorTagBase 0x80540000 2020-04-22 16:41:00.906 789-4577/? I/SS_3A: INFO: AEC: AECAlgoSetNodeInterface: 670: AF vendorTagBase 0x805a0000 2020-04-22 16:41:00.906 789-4577/? I/SS_3A: INFO: AEC: CreateAEC: 429: StaticAEC algo sizeOfParam for CHISTATSHANDLE is 12 2020-04-22 16:41:00.906 789-4577/? I/SS_3A: INFO: AEC: CreateAEC: 440: AEC_VendorTag:: Lens Facing = 0 chiSessionHandle = 0xd6266540 2020-04-22 16:41:00.906 789-4577/? I/SS_3A: INFO: AEC: CreateAEC: 466: StaticAEC algo create: camera open mask 2 2020-04-22 16:41:00.906 789-4577/? I/SS_3A: INFO: AEC: CreateAEC: 423: aec_camera_info 0 1 0 2020-04-22 16:41:00.906 789-4577/? I/SS_3A: INFO: AEC: CreateAEC: 481: CreateAECAlgorithm (refcnt:0 cameraId 1 OverrideCameraOpen 1 lensFacing 0 pAECSingletonWrapper->pAECSingleton 0x0 2020-04-22 16:41:00.908 789-4577/? I/SS_3A: INFO: AEC: TsAecFront_load_lib: 195: TsAec_ptr 0x54e2eb67 2020-04-22 16:41:00.909 789-4577/? D/SensorListener: sensor_listener_load is called! 2020-04-22 16:41:00.909 789-4577/? D/SensorListener: sensorListenerLoad is called! m_sensorListener(0x0) 2020-04-22 16:41:00.909 789-4577/? D/SensorListener: SensorListener is called! 2020-04-22 16:41:00.909 789-4577/? D/SensorListener: numSensors=27 2020-04-22 16:41:00.909 789-4577/? I/SS_3A: INFO: AEC: LoadAlgorithmAndSymbols: 368: [AEC] Custom Algo handle 0xe70d9800 2020-04-22 16:41:00.909 789-4577/? I/SS_3A: INFO: AEC: CreateAEC: 527: CreateAECAlgorithm 0xee576168 2020-04-22 16:41:00.909 789-4577/? I/SS_3A: INFO: AEC: TsAec_set_crop_roi: 649: Invalid sensor or crop window info 0 0 0 0 2020-04-22 16:41:00.909 789-4577/? I/SS_3A: INFO: AEC: TsAecFront_Set_Exp_Table: 361: [Exp_Table::Front] Shooting=-1, ExpDiagram=4, m_video=0, m_fdae=0, rec=0, hfr=0, lightcon=0 2020-04-22 16:41:00.909 789-4577/? I/SS_3A: INFO: AEC: TSAecFront_set_param: 750: AECAlgoOpMode 3 aeStartMode 1 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: AWBAlgoSetNodeInterface: 642: AWBAlgoSetNodeInterface 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: AWBAlgoSetNodeInterface: 670: AWB vendorTagBase 0x80540000 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: AWBAlgoSetNodeInterface: 679: AEC vendorTagBase 0x80530000 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: AWBAlgoSetNodeInterface: 688: AF vendorTagBase 0x805a0000 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 475: statsStreamInitConfig operationMode 1 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 422: StaticAWB algo sizeOfParam for CHISTATSHANDLE is 12 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 431: Lens Facing = 0 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 456: StaticAWB algo create: overrideCameraOpen is 2 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 466: cameraInfo algoRole 0, cameraId 1, cameraType 0 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 490: CreateAWBAlgorithm (refcnt:0 cameraId 1 OverrideCameraOpen 1 lensFacing 0 pAWBSingletonWrapper->pAWB 0x0 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 520: Create AWBAlgorithm, pAWB 0xee576190 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: LoadAlgorithmAndSymbols: 366: Loaded AWB algorithm and Symbols : E08QL_libTsAwbFront.so! 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: LoadAlgorithmAndSymbols: 389: pInstance->hAWBAlgorithm 0xe82dae00 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: TsAwb_get_cal_data_front: 120: module 431 774 772 383 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: TsAwb_get_cal_data_front: 121: master 65535 65535 65535 65535 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: TsAwb_get_cal_data_front: 123: module RG 0.557568 , BG 0.495472 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: TsAwb_get_cal_data_front: 124: master RG 65535.000000 , BG 65535.000000 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: TsAwb_init_with_mode: 159: [eLensType 0] Preset_gain = 1.666342, 1.747700 2020-04-22 16:41:00.910 789-4577/? I/SS_3A: INFO: AWB: TsAwb_init_with_mode: 163: [eLensType 0] Adjust_gain = 1.017245, 0.970024 2020-04-22 16:41:00.913 789-4577/? D/GTMDRV: [gtmdrv] cGtmBase::version : 0x2190402, this = 0xea873400 2020-04-22 16:41:00.913 789-4577/? D/GTMDRV: [gtmdrv] cGtmBeyond::version : 0x2190319, this = 0xea873400 2020-04-22 16:41:00.929 789-12260/? E/CHI: [SS_ERR ]: [CHI_DEFAULT ]: chxadvancedcamerausecase.cpp: Initialize: 4050: [ERROR] Sec: Invalid Format: 0x23 2020-04-22 16:41:00.932 789-12260/? E/CamX: [ERROR][PPROC ] camxifenode.cpp:1028 Create() Unhandled node property Id 1 2020-04-22 16:41:00.934 789-12260/? E/CamX: [ERROR][PPROC ] camxifenode.cpp:1028 Create() Unhandled node property Id 1 2020-04-22 16:41:00.941 789-12260/? I/SecCamDevSession@3.4-impl: [CAMKPI] camera3->configure_streams : X 2020-04-22 16:41:00.941 952-1582/? D/Camera3-Device: configureStreams : stream[0] : format = 0x23, data_space = 0x8c20000, max_buffers = 8 2020-04-22 16:41:00.941 786-839/? E/ANDR-PERF-MPCTL: Invalid profile no. 0, total profiles 0 only 2020-04-22 16:41:00.942 952-1582/? D/Camera3-OutputStream: configureConsumerQueueLocked: Consumer wants 2 buffers, HAL wants 8 2020-04-22 16:41:00.943 952-1582/? D/Camera3-Device: Set real time priority for request queue thread (tid 4575) 2020-04-22 16:41:00.943 16932-16949/? E/CameraV2Controller: onConfigured 2020-04-22 16:41:00.944 789-1531/? I/SS_3A: INFO: AWB: TsAwb_parse_stats: 1365: statsBayerGrid startupMode = StatisticsStartUpInvalid, exit now 2020-04-22 16:41:00.944 789-1534/? I/SS_3A: INFO: AEC: TSAecFront_set_param: 941: AECManualSetting mode=0 ISOValue=50 exposureTime=20068 gain=0.000 2020-04-22 16:41:00.944 789-1534/? I/SS_3A: INFO: AEC: TSAecFront_set_param: 982: AECAlgoSetParamAECLock 0 2020-04-22 16:41:00.944 789-1534/? I/SS_3A: INFO: AEC: TsAec_parse_aec_bg_stats: 1860: [1] AEC BG StatStruct SKIP exit now 2020-04-22 16:41:00.944 789-1534/? I/SS_3A: INFO: AEC: TsAecGetVendorTagInfo: 2192: pCaptureIntent=1 recordinghint 0 2020-04-22 16:41:00.944 789-1534/? I/SS_3A: INFO: AEC: TsAecFront_set_hdr_mode: 180: hdr 2, hdr_ui 2, isVideoMode 0 2020-04-22 16:41:00.944 789-1534/? I/SS_3A: INFO: AEC: TsAecGetVendorTagInfo: 2331: aeMode 1 2020-04-22 16:41:00.945 789-1534/? I/SS_3A: INFO: AEC: TsAec_process_get_aec_info: 917: [0][1] algo_out Av=2.273 g=1.000 e_time=0.002 IsLLS=0x0 Ev=12.500 Bv=7.500 ProEv=12.500 Cvgd=0 lux=141, lls=0x0, drc=1.000 wide = 0 2020-04-22 16:41:00.945 1449-2099/? I/EDMNativeHelperService: isFaceRecognitionAllowedEvenCameraBlocked 2020-04-22 16:41:00.945 789-1534/? I/SS_3A: INFO: AWB: TsAwb_parse_stats: 1365: statsBayerGrid startupMode = StatisticsStartUpInvalid, exit now 2020-04-22 16:41:00.945 789-1531/? I/SS_3A: INFO: AEC: TsAec_parse_aec_bg_stats: 1860: [2] AEC BG StatStruct SKIP exit now 2020-04-22 16:41:00.946 1449-2099/? I/EDMNativeHelperService: isCameraEnabled 2020-04-22 16:41:00.946 789-1528/? I/SS_3A: INFO: AEC: TSAecFront_set_param: 750: AECAlgoOpMode 3 aeStartMode 1 2020-04-22 16:41:00.946 789-1528/? I/SS_3A: INFO: AEC: TsAec_parse_aec_bg_stats: 1860: [3] AEC BG StatStruct SKIP exit now 2020-04-22 16:41:00.946 789-1534/? I/SS_3A: INFO: AWB: TsAwb_parse_stats: 1365: statsBayerGrid startupMode = StatisticsStartUpInvalid, exit now 2020-04-22 16:41:00.947 1449-2099/? D/RestrictionPolicy: isCameraEnabled ret(true) userId(0) cxtInfo.mCallerUid(1000) cxtInfo.mContainerId(0) 2020-04-22 16:41:00.947 952-1582/? D/Camera3-Device: setStreamingRequestList: Enter: calling submitRequestsHelper: repeating = true 2020-04-22 16:41:00.947 952-1582/? D/Camera3-Device: submitRequestsHelper: mInterfaceLock.lock() 2020-04-22 16:41:00.947 952-1582/? D/Camera3-Status: markComponent: Component 1 is now active 2020-04-22 16:41:00.947 952-1582/? D/Camera3-Device: submitRequestsHelper: mInterfaceLock.unlock(), lastFrameNumber = -1 2020-04-22 16:41:00.947 16932-16949/? E/CameraV2Controller: onActive 2020-04-22 16:41:00.947 16932-16948/? E/CameraV2Controller: handleMessage signal: 1 2020-04-22 16:41:00.948 16932-16948/? E/CameraV2Controller: handleMessage(1) before let go!{ when=-92ms what=1 arg1=4 target=com.samsung.android.smartface.CameraV2Controller$EventHandler } 2020-04-22 16:41:00.948 16932-16948/? E/CameraV2Controller: handleMessage(1) after let go!{ when=-92ms what=1 arg1=4 target=com.samsung.android.smartface.CameraV2Controller$EventHandler } 2020-04-22 16:41:00.948 16932-16948/? E/CameraV2Controller: handleMessage, lock.unlock before 2020-04-22 16:41:00.948 16932-16948/? E/CameraV2Controller: handleMessage(1) { when=-92ms what=1 arg1=4 target=com.samsung.android.smartface.CameraV2Controller$EventHandler } 2020-04-22 16:41:00.948 16932-11428/? E/CameraController: waitHere, 92ms 2020-04-22 16:41:00.948 16932-11428/? E/CameraController: Wait for 1 DONE! 2020-04-22 16:41:00.948 16932-11428/? E/CameraController: sendMessageAndWait, lock.unlock before 2020-04-22 16:41:00.948 16932-11428/? E/CameraController: sendMessageAndWait, lock.unlock after 2020-04-22 16:41:00.948 16932-11428/? E/CameraController: openCamera E 2020-04-22 16:41:00.948 952-4575/? D/Camera3-Status: markComponent: Component 2 is now active 2020-04-22 16:41:00.948 952-4575/? D/Camera3-Status: markComponent: Component 0 is now active 2020-04-22 16:41:00.948 1449-4572/? E/SmartFaceManager: Listener does not implements SmartFaceInfoListener2 2020-04-22 16:41:00.949 789-12260/? I/SecCamDevSession@3.4-impl: [CAMKPI] camera3->process_capture_request : E 2020-04-22 16:41:00.975 789-1532/? I/SS_3A: INFO: AEC: TsAec_process_get_aec_info: 917: [0][4] algo_out Av=2.273 g=1.809 e_time=0.008 IsLLS=0x0 Ev=9.500 Bv=-2.195 ProEv=2.805 Cvgd=0 lux=368, lls=0x0, drc=1.000 wide = 0 2020-04-22 16:41:00.983 789-1530/? I/SS_3A: INFO: AEC: TsAec_process_get_aec_info: 917: [0][5] algo_out Av=2.273 g=14.475 e_time=0.008 IsLLS=0x0 Ev=6.500 Bv=-2.195 ProEv=2.805 Cvgd=0 lux=368, lls=0x0, drc=1.000 wide = 0 2020-04-22 16:41:01.000 789-1532/? I/SS_3A: INFO: AEC: TsAec_process_get_aec_info: 917: [0][7] algo_out Av=2.273 g=40.610 e_time=0.008 IsLLS=0x0 Ev=5.012 Bv=-1.477 ProEv=3.523 Cvgd=0 lux=351, lls=0x0, drc=1.000 wide = 0 2020-04-22 16:41:01.008 789-1530/? I/SS_3A: INFO: AEC: TsAec_process_get_aec_info: 917: [0][8] algo_out Av=2.273 g=103.351 e_time=0.008 IsLLS=0x0 Ev=3.664 Bv=-1.195 ProEv=3.805 Cvgd=0 lux=345, lls=0x0, drc=1.000 wide = 0 2020-04-22 16:41:01.053 789-4577/? I/SS_3A: INFO: AEC: TsAECDestroy: 257: overrideCameraClose in AECDestroy Camera close mask 5 2020-04-22 16:41:01.053 789-4577/? I/SS_3A: INFO: AEC: TsAECDestroy: 263: pCameraInfo->cameraid in AECDestroy 1 2020-04-22 16:41:01.053 789-4577/? I/SS_3A: INFO: AEC: TsAECDestroy: 285: TsAECDestroy (refcnt:1 cameraId 1 overrideCameraClose 0 lensFacing: 0 pAECAlgorithm 0xee576168 pAECSingletonWrapper->pAECSingleton 0xee576168 2020-04-22 16:41:01.053 789-4577/? I/SS_3A: INFO: AEC: TsAECDestroy: 311: Not Actual Destroy 0 2020-04-22 16:41:01.053 789-4577/? I/SS_3A: INFO: AEC: TsAecFrontModeChange: 727: ModeChange! 2020-04-22 16:41:01.053 789-4577/? I/SS_3A: INFO: AWB: TsAwbDestroy: 271: overrideCameraClose in AWBDestroy 5 2020-04-22 16:41:01.053 789-4577/? I/SS_3A: INFO: AWB: TsAwbDestroy: 277: cameraId in AWBDestroy 1 2020-04-22 16:41:01.053 789-4577/? I/SS_3A: INFO: AWB: TsAwbDestroy: 299: TsAwbDestroy (refcnt:1 cameraId 1 overrideCameraClose 0 lensFacing 0 pAWBSingletonWrapper->pAWB 0xee576190 2020-04-22 16:41:01.053 789-4577/? I/SS_3A: INFO: AWB: TsAwbDestroy: 328: Not Actual Destroy 0 2020-04-22 16:41:01.055 789-4577/? D/GTMDRV: [gtmdrv] DeinitGtmdrv phandler addr: 0xea873400 2020-04-22 16:41:01.055 789-4577/? D/GTMDRV: [gtmdrv] DeinitGtmdrv found addr: 0xea873400, index = 0 2020-04-22 16:41:01.060 789-12260/? E/CamX: [ERROR][SENSOR ] camxsensornode.cpp:5682 GetSensorModuleIndexes() GetDeviceIndex failure for Flash, no flash hardware found 2020-04-22 16:41:01.062 789-12260/? E/CamX: [ERROR][ISP ] camxifenode.cpp:8211 FinalizeBufferProperties() ERROR pInputPortRequirement->optimalHeightis 0 2020-04-22 16:41:01.062 789-12260/? E/CamX: [ERROR][ISP ] camxifenode.cpp:8211 FinalizeBufferProperties() ERROR pInputPortRequirement->optimalHeightis 0 2020-04-22 16:41:01.062 789-12260/? E/CamX: [ERROR][ISP ] camxifenode.cpp:8222 FinalizeBufferProperties() ERROR perOutputPortOptimalHeight is 0 2020-04-22 16:41:01.062 789-12260/? E/CamX: [ERROR][CORE ] camxnode.cpp:3444 RemoveOutputDeviceIndices() Node::Preview_IFE0 No device indice found for removal 2020-04-22 16:41:01.062 789-12260/? I/chatty: uid=1047(cameraserver) HwBinder:789_4 identical 1 line 2020-04-22 16:41:01.062 789-12260/? E/CamX: [ERROR][CORE ] camxnode.cpp:3444 RemoveOutputDeviceIndices() Node::Preview_IFE0 No device indice found for removal 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: AECAlgoSetNodeInterface: 650: AEC vendorTagBase 0x80530000 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: AECAlgoSetNodeInterface: 660: AWB vendorTagBase 0x80540000 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: AECAlgoSetNodeInterface: 670: AF vendorTagBase 0x805a0000 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: CreateAEC: 429: StaticAEC algo sizeOfParam for CHISTATSHANDLE is 12 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: CreateAEC: 440: AEC_VendorTag:: Lens Facing = 0 chiSessionHandle = 0xe2406540 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: CreateAEC: 466: StaticAEC algo create: camera open mask 2 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: CreateAEC: 423: aec_camera_info 0 1 0 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: CreateAEC: 481: CreateAECAlgorithm (refcnt:0 cameraId 1 OverrideCameraOpen 1 lensFacing 0 pAECSingletonWrapper->pAECSingleton 0xee576168 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: CreateAEC: 541: ReCreate AECAlgorithm 0xee576168 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: TsAec_set_crop_roi: 649: Invalid sensor or crop window info 800 600 0 0 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: TSAecFront_set_param: 750: AECAlgoOpMode 1 aeStartMode 0 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AEC: TsAECAlgoGetParamStartExposure: 433: Ev=3.805,et=0.008,g=93.752,l_cnt=633, l_et=0.008, l_g=93.752, l_cnt=633,outRH=0 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: AWBAlgoSetNodeInterface: 642: AWBAlgoSetNodeInterface 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: AWBAlgoSetNodeInterface: 670: AWB vendorTagBase 0x80540000 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: AWBAlgoSetNodeInterface: 679: AEC vendorTagBase 0x80530000 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: AWBAlgoSetNodeInterface: 688: AF vendorTagBase 0x805a0000 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 475: statsStreamInitConfig operationMode 0 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 422: StaticAWB algo sizeOfParam for CHISTATSHANDLE is 12 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 431: Lens Facing = 0 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 456: StaticAWB algo create: overrideCameraOpen is 2 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 466: cameraInfo algoRole 0, cameraId 1, cameraType 0 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 490: CreateAWBAlgorithm (refcnt:0 cameraId 1 OverrideCameraOpen 1 lensFacing 0 pAWBSingletonWrapper->pAWB 0xee576190 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: CreateAWBAlgorithm: 556: ReCreate AWBAlgorithm, pAWB 0xee576190 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: TsAwb_get_cal_data_front: 120: module 431 774 772 383 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: TsAwb_get_cal_data_front: 121: master 65535 65535 65535 65535 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: TsAwb_get_cal_data_front: 123: module RG 0.557568 , BG 0.495472 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: TsAwb_get_cal_data_front: 124: master RG 65535.000000 , BG 65535.000000 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: TsAwb_init_with_mode: 159: [eLensType 0] Preset_gain = 1.666342, 1.747700 2020-04-22 16:41:01.063 789-12260/? I/SS_3A: INFO: AWB: TsAwb_init_with_mode: 163: [eLensType 0] Adjust_gain = 1.017245, 0.970024 2020-04-22 16:41:01.064 789-12260/? I/SS_3A: INFO: AF: PDLibSetAlgoInterface: 2238: PDAF vendorTagBase 0x80590000 2020-04-22 16:41:01.064 789-12260/? I/SS_3A: INFO: AF: PDLibSetAlgoInterface: 2245: AF vendorTagBase 0x805a0000 2020-04-22 16:41:01.064 789-12260/? I/SS_3A: INFO: AF: CreateAFAlgorithm: 380: StaticAF algo sizeOfParam for CHISTATSHANDLE is 12 2020-04-22 16:41:01.064 789-12260/? I/SS_3A: INFO: AF: CreateAFAlgorithm: 389: Lens Facing(currentCam) = 0 2020-04-22 16:41:01.064 789-12260/? I/SS_3A: INFO: AF: CreateAFAlgorithm: 374: af_camera_info 1 2020-04-22 16:41:01.064 789-12260/? I/SS_3A: INFO: AF: CreateAFAlgorithm: 466: CreateAFAlgorithm (refcnt:0 cameraId 1 overrideCameraOpen 1 lensFacing 0 pAFSingletonWrapper->pAFSingleton 0x0 2020-04-22 16:41:01.064 789-12260/? I/SS_3A: INFO: AF: CreateAFAlgorithm: 500: SS_AF_LIB loading cam[0] 2020-04-22 16:41:01.065 789-12260/? I/SS_3A: INFO: AF: TsAf_init_front: 2462: libptr: 0x73a93d99 E 2020-04-22 16:41:01.065 789-12260/? E/SS_3A: ERROR: AF: TsAf_get_cal_data_front: 1641: Front camera PDAF CAL Version 1633 i_MultiUse 1 2020-04-22 16:41:01.065 789-12260/? E/FRONT_AF_DEBUG: [cam_id_0][CMG_EFL] FrontAfLib_BootingInit (line 94) : Front Version = 0xf680 Chip Type = 1 (0:LSI, 1:QC) 2020-04-22 16:41:01.065 789-12260/? E/FRONT_AF_DEBUG: [cam_id_0][CMG_EFL] FrontAfLib_BootingInit (line 270) : af cal data far: -1, middle: -2142371840, near: 5, add_80: -221138016, add_50: -296752032, plus_offset: 65535, minus_offset: 65535 2020-04-22 16:41:01.065 789-12260/? E/FRONT_AF_DEBUG: [cam_id_0][CMG_EFL] FrontAfLib_BootingInit (line 302) : paf_cal_data_info = 1633 paf_cal_errcheck 0x07180667 2020-04-22 16:41:01.065 789-12260/? E/FRONT_AF_DEBUG: [CMG_EFL] FrontAfLib_BootingInit (line 312) QCSWPD enabled: paf_cal_data_info = 1633 2020-04-22 16:41:01.065 789-12260/? E/FRONT_AF_DEBUG: [cam_id_0][CMG_EFL] FrontAfLib_AlgorithmInit (line 450) focus mode = 0 paf_stats_valid : 0 2020-04-22 16:41:01.065 789-12260/? E/FRONT_AF_DEBUG: [CMG_AF_FUNC] Front_FactoryAfInit (line 2053) 2020-04-22 16:41:01.065 789-12260/? E/FRONT_AF_DEBUG: [cam_id_0][CMG_EFL] Front_UpdateAfStatus (line 281) : AF status 0x2 0x0 2020-04-22 16:41:01.065 789-12260/? E/FRONT_AF_DEBUG: [CMG_AF][idkim] FACTORY AF CAL Start !!!!! 2020-04-22 16:41:01.067 789-12260/? D/GTMDRV: [gtmdrv] cGtmBase::version : 0x2190402, this = 0xeaa5d400 2020-04-22 16:41:01.067 789-12260/? D/GTMDRV: [gtmdrv] cGtmBeyond::version : 0x2190319, this = 0xeaa5d400 2020-04-22 16:41:01.102 789-12260/? I/SecCamDevSession@3.4-impl: [CAMKPI] camera3->process_capture_request : X 2020-04-22 16:41:01.102 789-1527/? I/SS_3A: INFO: AWB: TsAwb_parse_stats: 1365: statsBayerGrid startupMode = StatisticsStartUpInvalid, exit now 2020-04-22 16:41:01.102 789-1531/? I/SS_3A: INFO: AEC: TSAecFront_set_param: 941: AECManualSetting mode=0 ISOValue=50 exposureTime=26490 gain=0.000 2020-04-22 16:41:01.102 789-1531/? I/SS_3A: INFO: AEC: TsAec_parse_aec_bg_stats: 1860: [1] AEC BG StatStruct SKIP exit now 2020-04-22 16:41:01.102 789-1531/? I/SS_3A: INFO: AEC: TsAecFront_Set_Exp_Table: 361: [Exp_Table::Front] Shooting=1, ExpDiagram=0, m_video=0, m_fdae=1, rec=0, hfr=0, lightcon=0 2020-04-22 16:41:01.102 789-1531/? I/SS_3A: INFO: AEC: TsAec_process_get_aec_info: 917: [0][1] algo_out Av=2.273 g=13.024 e_time=0.060 IsLLS=0x0 Ev=3.805 Bv=-1.195 ProEv=3.805 Cvgd=0 lux=345, lls=0x0, drc=1.000 wide = 0 2020-04-22 16:41:01.103 789-1530/? E/CamX: [ERROR][ISP ] camxifenode.cpp:3229 Get3AFrameConfig() Invalid BF config use hardcode for requestId 1 2020-04-22 16:41:01.104 789-1534/? I/SS_3A: INFO: AWB: TsAwb_parse_stats: 1365: statsBayerGrid startupMode = StatisticsStartUpInvalid, exit now 2020-04-22 16:41:01.104 789-1533/? I/SS_3A: INFO: AEC: TsAec_parse_aec_bg_stats: 1860: [2] AEC BG StatStruct SKIP exit now 2020-04-22 16:41:01.105 789-1534/? I/SS_3A: INFO: AEC: TSAecFront_set_param: 750: AECAlgoOpMode 1 aeStartMode 0 2020-04-22 16:41:01.105 789-1534/? I/SS_3A: INFO: AEC: TsAECAlgoGetParamStartExposure: 433: Ev=3.805,et=0.067,g=11.719,l_cnt=5064, l_et=0.067, l_g=11.719, l_cnt=5064,outRH=0 2020-04-22 16:41:01.105 789-1534/? I/SS_3A: INFO: AEC: TsAec_parse_aec_bg_stats: 1860: [3] AEC BG StatStruct SKIP exit now 2020-04-22 16:41:01.105 789-1534/? I/SS_3A: INFO: AWB: TsAwb_parse_stats: 1365: statsBayerGrid startupMode = StatisticsStartUpInvalid, exit now 2020-04-22 16:41:01.233 789-1529/? I/SS_3A: INFO: AEC: TsAec_process_get_aec_info: 917: [0][4] algo_out Av=2.273 g=11.879 e_time=0.067 IsLLS=0x0 Ev=3.785 Bv=-1.234 ProEv=3.766 Cvgd=0 lux=345, lls=0x0, drc=1.000 wide = 0 2020-04-22 16:41:01.241 789-1527/? I/SecCamDevSession@3.4-impl: [CAMKPI] camera3->process_capture_result : E 2020-04-22 16:41:01.242 952-1099/? W/Camera3-OutputStream: returnBufferCheckedLocked: A frame is dropped for stream 0 due to buffer error. 2020-04-22 16:41:01.242 789-1527/? I/SecCamDevSession@3.4-impl: [CAMKPI] camera3->process_capture_result : X 2020-04-22 16:41:01.301 789-1528/? I/SS_3A: INFO: AEC: TsAec_process_get_aec_info: 917: [0][5] algo_out Av=2.273 g=11.911 e_time=0.067 IsLLS=0x0 Ev=3.781 Bv=-1.219 ProEv=3.781 Cvgd=1 lux=345, lls=0x0, drc=1.123 wide = 0 2020-04-22 16:41:01.309 952-1099/? W/Camera3-OutputStream: returnBufferCheckedLocked: A frame is dropped for stream 0 due to buffer error. 2020-04-22 16:41:01.377 16932-16949/? E/CameraV2Controller: onImageAvailable width :320 height 240 2020-04-22 16:41:01.378 16932-16949/? E/SmartFaceService: Orientation: 1 2020-04-22 16:41:01.378 16932-16949/? E/SmartFaceService: Calling onPreviewFrame: Thread[Stay/Rotation Worker,5,main]/284045920 2020-04-22 16:41:01.378 16932-16950/? D/Stay/Rotation Worker: handleMessage: 1 2020-04-22 16:41:01.379 16932-16950/? E/SmartScreen_JNI: processImage start : prev time [ 128012 ] 2020-04-22 16:41:01.379 16932-16950/? D/SmartScreen_JNI: tmpfile : /data/user/0/com.samsung.android.smartface/files/smart_stay_hash_0.dmc 2020-04-22 16:41:01.379 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay orientation [1] count [0] 2020-04-22 16:41:01.379 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : FUNCTION START img width 320, height 240 previous angle 1 2020-04-22 16:41:01.379 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : loop Start with dir 0, width 320 and heighe 240 2020-04-22 16:41:01.379 16932-16950/? D/DMCFaceEngine: caFace_RunFaceDetection_SmartStayRotation : FaceDetection Start with width 320, heighe 240 2020-04-22 16:41:01.379 16932-16950/? D/DMCFaceEngine: fd_find_run : Use A New Sensor ==========> 0 [320][240] 2020-04-22 16:41:01.379 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(34 x 25) / Src(320 x 240) / Search(12 x 1) / 4, 3 2020-04-22 16:41:01.379 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(36 x 27) / Src(320 x 240) / Search(14 x 3) / 5, 0 2020-04-22 16:41:01.379 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(38 x 28) / Src(320 x 240) / Search(16 x 4) / 6, 2 2020-04-22 16:41:01.379 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(40 x 30) / Src(320 x 240) / Search(18 x 6) / 7, 3 2020-04-22 16:41:01.379 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(46 x 34) / Src(320 x 240) / Search(24 x 10) / 9, 0 2020-04-22 16:41:01.379 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(43 x 32) / Src(320 x 240) / Search(21 x 8) / 8, 2 2020-04-22 16:41:01.380 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(50 x 37) / Src(320 x 240) / Search(28 x 13) / 10, 3 2020-04-22 16:41:01.380 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(54 x 40) / Src(320 x 240) / Search(32 x 16) / 11, 0 2020-04-22 16:41:01.380 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(58 x 43) / Src(320 x 240) / Search(36 x 19) / 12, 2 2020-04-22 16:41:01.380 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(62 x 46) / Src(320 x 240) / Search(40 x 22) / 13, 3 2020-04-22 16:41:01.380 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(65 x 48) / Src(320 x 240) / Search(43 x 24) / 14, 2 2020-04-22 16:41:01.380 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(74 x 55) / Src(320 x 240) / Search(52 x 31) / 15, 0 2020-04-22 16:41:01.381 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(85 x 63) / Src(320 x 240) / Search(63 x 39) / 16, 3 2020-04-22 16:41:01.381 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(95 x 71) / Src(320 x 240) / Search(73 x 47) / 17, 2 2020-04-22 16:41:01.381 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(110 x 82) / Src(320 x 240) / Search(88 x 58) / 18, 0 2020-04-22 16:41:01.383 16932-16950/? D/DMCFaceEngine: fd_find_run : Use A New Sensor ==========> 0 [320][240] 2020-04-22 16:41:01.383 16932-16981/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(34 x 25) / Src(320 x 240) / Search(12 x 1) / 4, 1 2020-04-22 16:41:01.383 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(36 x 27) / Src(320 x 240) / Search(14 x 3) / 5, 2 2020-04-22 16:41:01.383 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(38 x 28) / Src(320 x 240) / Search(16 x 4) / 6, 0 2020-04-22 16:41:01.384 16932-16981/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(40 x 30) / Src(320 x 240) / Search(18 x 6) / 7, 1 2020-04-22 16:41:01.384 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(43 x 32) / Src(320 x 240) / Search(21 x 8) / 8, 2 2020-04-22 16:41:01.384 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(46 x 34) / Src(320 x 240) / Search(24 x 10) / 9, 0 2020-04-22 16:41:01.384 16932-16981/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(50 x 37) / Src(320 x 240) / Search(28 x 13) / 10, 1 2020-04-22 16:41:01.384 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(54 x 40) / Src(320 x 240) / Search(32 x 16) / 11, 0 2020-04-22 16:41:01.384 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(58 x 43) / Src(320 x 240) / Search(36 x 19) / 12, 2 2020-04-22 16:41:01.384 16932-16981/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(62 x 46) / Src(320 x 240) / Search(40 x 22) / 13, 1 2020-04-22 16:41:01.384 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(65 x 48) / Src(320 x 240) / Search(43 x 24) / 14, 0 2020-04-22 16:41:01.384 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(74 x 55) / Src(320 x 240) / Search(52 x 31) / 15, 2 2020-04-22 16:41:01.385 16932-16981/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(85 x 63) / Src(320 x 240) / Search(63 x 39) / 16, 1 2020-04-22 16:41:01.385 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(95 x 71) / Src(320 x 240) / Search(73 x 47) / 17, 0 2020-04-22 16:41:01.385 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(110 x 82) / Src(320 x 240) / Search(88 x 58) / 18, 2 2020-04-22 16:41:01.387 16932-16950/? D/DMCFaceEngine: fd_find_run : Use A New Sensor ==========> 0 [320][240] 2020-04-22 16:41:01.387 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(34 x 25) / Src(320 x 240) / Search(12 x 1) / 4, 3 2020-04-22 16:41:01.387 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(36 x 27) / Src(320 x 240) / Search(14 x 3) / 5, 0 2020-04-22 16:41:01.387 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(38 x 28) / Src(320 x 240) / Search(16 x 4) / 6, 2 2020-04-22 16:41:01.387 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(40 x 30) / Src(320 x 240) / Search(18 x 6) / 7, 3 2020-04-22 16:41:01.387 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(43 x 32) / Src(320 x 240) / Search(21 x 8) / 8, 0 2020-04-22 16:41:01.387 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(46 x 34) / Src(320 x 240) / Search(24 x 10) / 9, 2 2020-04-22 16:41:01.387 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(50 x 37) / Src(320 x 240) / Search(28 x 13) / 10, 3 2020-04-22 16:41:01.387 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(54 x 40) / Src(320 x 240) / Search(32 x 16) / 11, 0 2020-04-22 16:41:01.388 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(62 x 46) / Src(320 x 240) / Search(40 x 22) / 13, 3 2020-04-22 16:41:01.388 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(58 x 43) / Src(320 x 240) / Search(36 x 19) / 12, 2 2020-04-22 16:41:01.388 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(65 x 48) / Src(320 x 240) / Search(43 x 24) / 14, 0 2020-04-22 16:41:01.389 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(74 x 55) / Src(320 x 240) / Search(52 x 31) / 15, 3 2020-04-22 16:41:01.389 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(85 x 63) / Src(320 x 240) / Search(63 x 39) / 16, 2 2020-04-22 16:41:01.389 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(95 x 71) / Src(320 x 240) / Search(73 x 47) / 17, 0 2020-04-22 16:41:01.390 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(110 x 82) / Src(320 x 240) / Search(88 x 58) / 18, 3 2020-04-22 16:41:01.391 16932-16950/? D/DMCFaceEngine: fd_find_run : Use A New Sensor ==========> 0 [110][110] 2020-04-22 16:41:01.391 16932-16950/? D/DMCFaceEngine: caFace_RunFaceDetection_SmartStayRotation : Detected No Face 2020-04-22 16:41:01.392 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : loop FD End dir 0 with nResult -1 2020-04-22 16:41:01.392 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : FOR DETECTION LOOP Start 2020-04-22 16:41:01.392 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : SINGLE EYE DETECTION LOOP START 2020-04-22 16:41:01.392 16932-16950/? D/DMCFaceEngine: caSmartDPSmartStay : single eye detection loop start dir 0 2020-04-22 16:41:01.392 16932-16950/? D/DMCFaceEngine: caIF_RunEyeDetection_MCT_IRS : caIF_RunEyeDetection_MCT_IRS START 2020-04-22 16:41:01.392 16932-16950/? D/DMCFaceEngine: caIF_RunEyeDetection_MCT_IRS : Width 320, Height 240 2020-04-22 16:41:01.399 2606-2606/? D/io_stats: !@ 8,0 r 2398463 72799104 w 3254065 108063692 d 340973 192970964 f 198086 361677 iot 2489600 1879171 th 102400 0 0 pt 0 inp 0 0 284045.941 2020-04-22 16:41:01.438 16932-16950/? D/DMCFaceEngine: caIF_RunEyeDetection_MCT_IRS : caIF_RunEyeDetection_MCT_IRS END 2020-04-22 16:41:01.438 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : caFace_Aux_FindEyes_IRS time 46283 2020-04-22 16:41:01.438 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : Single Eye Detection Fail dir 0 2020-04-22 16:41:01.438 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : loop End ED dir 0 with nResult -1 2020-04-22 16:41:01.438 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : SINGLE EYE DETECTION LOOP END with nResult -1 2020-04-22 16:41:01.438 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : DETECT HUMAN FAIL 2020-04-22 16:41:01.438 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : face : X[0] Y[0] W[0] H[0] 2020-04-22 16:41:01.438 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : eye : LX[0] LY[0] RX[0] RY[0] 2020-04-22 16:41:01.438 16932-16950/? D/DMCFaceEngine: caSmartDP_DetermineFixedDevice : TmpFile exists /data/user/0/com.samsung.android.smartface/files/smart_stay_hash_0.dmc 2020-04-22 16:41:01.438 16932-16950/? D/DMCFaceEngine: caSmartDP_HashingImage : wd [80] ht [60] 2020-04-22 16:41:01.439 16932-16950/? D/DMCFaceEngine: caSmartDP_DetermineFixedDevice : FIXED OR NOT : difference 921.855417 2020-04-22 16:41:01.439 16932-16950/? D/DMCFaceEngine: caSmartDP_DetermineFixedDevice : TmpFile making start /data/user/0/com.samsung.android.smartface/files/smart_stay_hash_0.dmc 2020-04-22 16:41:01.439 16932-16950/? D/DMCFaceEngine: caSmartDP_DetermineFixedDevice : TmpFile making end /data/user/0/com.samsung.android.smartface/files/smart_stay_hash_0.dmc 2020-04-22 16:41:01.439 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay result [-1] - [5.000000] 2020-04-22 16:41:01.439 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : [5.000000] determine[921] result [-1] 2020-04-22 16:41:01.439 16932-16950/? E/SmartScreen_JNI: processImage end : function time [ 78659 ] [-1] 2020-04-22 16:41:01.439 16932-16950/? E/Stay/Rotation Worker: processSmartStay[60ms], ret: -1 needToStay: 0 mFrameCount: 0 mFixed: false 2020-04-22 16:41:01.439 1449-4572/? E/SmartFaceManager: checkForSmartStay onInfo: 100: 0 2020-04-22 16:41:01.444 16932-16949/? E/CameraV2Controller: onImageAvailable width :320 height 240 2020-04-22 16:41:01.445 16932-16949/? E/SmartFaceService: Orientation: 1 2020-04-22 16:41:01.445 16932-16949/? E/SmartFaceService: Calling onPreviewFrame: Thread[Stay/Rotation Worker,5,main]/284045988 2020-04-22 16:41:01.445 16932-16950/? D/Stay/Rotation Worker: handleMessage: 1 2020-04-22 16:41:01.445 16932-16950/? E/SmartScreen_JNI: processImage start : prev time [ 81060 ] 2020-04-22 16:41:01.446 16932-16950/? D/SmartScreen_JNI: tmpfile : /data/user/0/com.samsung.android.smartface/files/smart_stay_hash_0.dmc 2020-04-22 16:41:01.446 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay orientation [1] count [1] 2020-04-22 16:41:01.446 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : FUNCTION START img width 320, height 240 previous angle 1 2020-04-22 16:41:01.446 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : loop Start with dir 0, width 320 and heighe 240 2020-04-22 16:41:01.446 16932-16950/? D/DMCFaceEngine: caFace_RunFaceDetection_SmartStayRotation : FaceDetection Start with width 320, heighe 240 2020-04-22 16:41:01.446 16932-16950/? D/DMCFaceEngine: fd_find_run : Use A New Sensor ==========> 0 [320][240] 2020-04-22 16:41:01.446 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(34 x 25) / Src(320 x 240) / Search(12 x 1) / 4, 3 2020-04-22 16:41:01.446 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(36 x 27) / Src(320 x 240) / Search(14 x 3) / 5, 0 2020-04-22 16:41:01.446 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(38 x 28) / Src(320 x 240) / Search(16 x 4) / 6, 2 2020-04-22 16:41:01.446 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(40 x 30) / Src(320 x 240) / Search(18 x 6) / 7, 3 2020-04-22 16:41:01.446 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(43 x 32) / Src(320 x 240) / Search(21 x 8) / 8, 2 2020-04-22 16:41:01.446 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(46 x 34) / Src(320 x 240) / Search(24 x 10) / 9, 0 2020-04-22 16:41:01.447 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(50 x 37) / Src(320 x 240) / Search(28 x 13) / 10, 2 2020-04-22 16:41:01.447 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(54 x 40) / Src(320 x 240) / Search(32 x 16) / 11, 3 2020-04-22 16:41:01.447 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(58 x 43) / Src(320 x 240) / Search(36 x 19) / 12, 0 2020-04-22 16:41:01.447 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(62 x 46) / Src(320 x 240) / Search(40 x 22) / 13, 2 2020-04-22 16:41:01.448 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(65 x 48) / Src(320 x 240) / Search(43 x 24) / 14, 3 2020-04-22 16:41:01.448 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(74 x 55) / Src(320 x 240) / Search(52 x 31) / 15, 0 2020-04-22 16:41:01.448 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(85 x 63) / Src(320 x 240) / Search(63 x 39) / 16, 2 2020-04-22 16:41:01.448 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(95 x 71) / Src(320 x 240) / Search(73 x 47) / 17, 3 2020-04-22 16:41:01.449 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(110 x 82) / Src(320 x 240) / Search(88 x 58) / 18, 0 2020-04-22 16:41:01.450 16932-16950/? D/DMCFaceEngine: fd_find_run : Use A New Sensor ==========> 0 [320][240] 2020-04-22 16:41:01.450 16932-16981/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(34 x 25) / Src(320 x 240) / Search(12 x 1) / 4, 1 2020-04-22 16:41:01.450 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(36 x 27) / Src(320 x 240) / Search(14 x 3) / 5, 0 2020-04-22 16:41:01.450 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(38 x 28) / Src(320 x 240) / Search(16 x 4) / 6, 2 2020-04-22 16:41:01.451 16932-16981/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(40 x 30) / Src(320 x 240) / Search(18 x 6) / 7, 1 2020-04-22 16:41:01.451 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(43 x 32) / Src(320 x 240) / Search(21 x 8) / 8, 2 2020-04-22 16:41:01.451 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(46 x 34) / Src(320 x 240) / Search(24 x 10) / 9, 0 2020-04-22 16:41:01.451 16932-16981/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(50 x 37) / Src(320 x 240) / Search(28 x 13) / 10, 1 2020-04-22 16:41:01.451 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(54 x 40) / Src(320 x 240) / Search(32 x 16) / 11, 2 2020-04-22 16:41:01.451 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(58 x 43) / Src(320 x 240) / Search(36 x 19) / 12, 0 2020-04-22 16:41:01.451 16932-16981/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(62 x 46) / Src(320 x 240) / Search(40 x 22) / 13, 1 2020-04-22 16:41:01.451 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(65 x 48) / Src(320 x 240) / Search(43 x 24) / 14, 2 2020-04-22 16:41:01.452 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(74 x 55) / Src(320 x 240) / Search(52 x 31) / 15, 0 2020-04-22 16:41:01.452 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(85 x 63) / Src(320 x 240) / Search(63 x 39) / 16, 2 2020-04-22 16:41:01.452 16932-16981/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(95 x 71) / Src(320 x 240) / Search(73 x 47) / 17, 1 2020-04-22 16:41:01.453 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(110 x 82) / Src(320 x 240) / Search(88 x 58) / 18, 0 2020-04-22 16:41:01.454 16932-16950/? D/DMCFaceEngine: fd_find_run : Use A New Sensor ==========> 0 [320][240] 2020-04-22 16:41:01.454 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(34 x 25) / Src(320 x 240) / Search(12 x 1) / 4, 3 2020-04-22 16:41:01.454 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(36 x 27) / Src(320 x 240) / Search(14 x 3) / 5, 0 2020-04-22 16:41:01.454 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(38 x 28) / Src(320 x 240) / Search(16 x 4) / 6, 2 2020-04-22 16:41:01.454 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(40 x 30) / Src(320 x 240) / Search(18 x 6) / 7, 3 2020-04-22 16:41:01.454 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(43 x 32) / Src(320 x 240) / Search(21 x 8) / 8, 0 2020-04-22 16:41:01.454 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(46 x 34) / Src(320 x 240) / Search(24 x 10) / 9, 2 2020-04-22 16:41:01.455 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(50 x 37) / Src(320 x 240) / Search(28 x 13) / 10, 0 2020-04-22 16:41:01.455 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(54 x 40) / Src(320 x 240) / Search(32 x 16) / 11, 3 2020-04-22 16:41:01.455 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(58 x 43) / Src(320 x 240) / Search(36 x 19) / 12, 2 2020-04-22 16:41:01.455 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(62 x 46) / Src(320 x 240) / Search(40 x 22) / 13, 0 2020-04-22 16:41:01.455 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(65 x 48) / Src(320 x 240) / Search(43 x 24) / 14, 3 2020-04-22 16:41:01.455 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(74 x 55) / Src(320 x 240) / Search(52 x 31) / 15, 2 2020-04-22 16:41:01.456 16932-16950/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(85 x 63) / Src(320 x 240) / Search(63 x 39) / 16, 0 2020-04-22 16:41:01.456 16932-16983/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(95 x 71) / Src(320 x 240) / Search(73 x 47) / 17, 3 2020-04-22 16:41:01.456 16932-16982/? D/DMCFaceEngine: fd_scan_frontal_partial : Re(110 x 82) / Src(320 x 240) / Search(88 x 58) / 18, 2 2020-04-22 16:41:01.458 16932-16950/? D/DMCFaceEngine: fd_find_run : Use A New Sensor ==========> 0 [110][110] 2020-04-22 16:41:01.460 16932-16950/? I/chatty: uid=1000(system) Stay/Rotation W identical 2 lines 2020-04-22 16:41:01.461 16932-16950/? D/DMCFaceEngine: fd_find_run : Use A New Sensor ==========> 0 [110][110] 2020-04-22 16:41:01.461 16932-16950/? D/DMCFaceEngine: caFace_RunFaceDetection_SmartStayRotation : Detected No Face 2020-04-22 16:41:01.461 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : loop FD End dir 0 with nResult -1 2020-04-22 16:41:01.461 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : FOR DETECTION LOOP Start 2020-04-22 16:41:01.461 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : SINGLE EYE DETECTION LOOP START 2020-04-22 16:41:01.461 16932-16950/? D/DMCFaceEngine: caSmartDPSmartStay : single eye detection loop start dir 0 2020-04-22 16:41:01.462 16932-16950/? D/DMCFaceEngine: caIF_RunEyeDetection_MCT_IRS : caIF_RunEyeDetection_MCT_IRS START 2020-04-22 16:41:01.462 16932-16950/? D/DMCFaceEngine: caIF_RunEyeDetection_MCT_IRS : Width 320, Height 240 2020-04-22 16:41:01.509 16932-16950/? D/DMCFaceEngine: caIF_RunEyeDetection_MCT_IRS : caIF_RunEyeDetection_MCT_IRS END 2020-04-22 16:41:01.509 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : caFace_Aux_FindEyes_IRS time 48038 2020-04-22 16:41:01.509 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : Single Eye Detection Fail dir 0 2020-04-22 16:41:01.509 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : loop End ED dir 0 with nResult -1 2020-04-22 16:41:01.509 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : SINGLE EYE DETECTION LOOP END with nResult -1 2020-04-22 16:41:01.509 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : DETECT HUMAN FAIL 2020-04-22 16:41:01.509 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : face : X[0] Y[0] W[0] H[0] 2020-04-22 16:41:01.509 16932-16950/? D/DMCFaceEngine: caSmartDP_SmartStay : eye : LX[0] LY[0] RX[0] RY[0] 2020-04-22 16:41:01.509 16932-16950/? E/SmartScreen_JNI: processImage end : function time [ 83443 ] [-1] 2020-04-22 16:41:01.509 16932-16950/? E/Stay/Rotation Worker: processSmartStay[63ms], ret: -1 needToStay: 0 mFrameCount: 1 mFixed: false 2020-04-22 16:41:01.509 1449-4572/? E/SmartFaceManager: checkForSmartStay onInfo: 100: 0 2020-04-22 16:41:01.509 16932-11428/? E/SmartFaceService: setValue, key: empty key for ping value: empty value 2020-04-22 16:41:01.509 16932-11428/? E/SmartFaceService.Client: Wrong Command key from client. Ignore. 2020-04-22 16:41:01.510 16932-11428/? E/SmartFaceService: unregister:284046052 2020-04-22 16:41:01.510 16932-11428/? E/SmartFaceService: unregister 2020-04-22 16:41:01.510 16932-11428/? E/SmartFaceService: unregister client found. pid 1449 servicetype: 100 AppName: system 2020-04-22 16:41:01.510 16932-11428/? D/Stay/Rotation Worker: updateClientsDone. def. do nothing. 2020-04-22 16:41:01.510 16932-11428/? E/SmartFaceService: Service Type to Worker: 0 2020-04-22 16:41:01.510 16932-11428/? E/SmartFaceService: Last Active clients:1 Current Active clients: 0 2020-04-22 16:41:01.510 16932-11428/? E/SmartFaceService: No active clients. close camera 2020-04-22 16:41:01.510 16932-11428/? E/CameraController: closeCamera S 2020-04-22 16:41:01.510 16932-11428/? E/CameraController: sendMessageAndWait, lock.lock before 2020-04-22 16:41:01.510 16932-11428/? E/CameraController: sendMessageAndWait, lock.lock after 2020-04-22 16:41:01.510 16932-11428/? E/CameraController: Wait for 0 2020-04-22 16:41:01.510 16932-16948/? E/CameraV2Controller: handleMessage(0) before lock{ when=0 what=0 target=com.samsung.android.smartface.CameraV2Controller$EventHandler } 2020-04-22 16:41:01.510 16932-11428/? E/CameraController: waitHere, condition var. will be used. related lock release and re-acquired after await done. 2020-04-22 16:41:01.510 16932-16948/? E/CameraV2Controller: handleMessage, lock.lock after 2020-04-22 16:41:01.511 16932-16948/? E/CameraV2Controller: handleMessage(0) { when=0 what=0 target=com.samsung.android.smartface.CameraV2Controller$EventHandler } 2020-04-22 16:41:01.511 952-1582/? D/Camera3-Device: clearStreamingRequest: mInterfaceLock.lock() 2020-04-22 16:41:01.511 952-1582/? D/Camera3-Device: clearStreamingRequest: mInterfaceLock.unlock() 2020-04-22 16:41:01.512 16932-16949/? E/CameraV2Controller: onImageAvailable width :320 height 240 2020-04-22 16:41:01.512 952-1582/? E/CameraDeviceClient: Disconnect from CameraDeviceClient 2020-04-22 16:41:01.512 952-1582/? D/CameraDeviceClient: disconnect:stop IT Policy checking thread 2020-04-22 16:41:01.512 952-1582/? E/Camera2-FrameProcessorBase: removeListener: 2020-04-22 16:41:01.512 16932-16949/? E/SmartFaceService: Orientation: 1 2020-04-22 16:41:01.512 16932-16949/? E/SmartFaceService: Calling onPreviewFrame: Thread[Stay/Rotation Worker,5,main]/284046055 2020-04-22 16:41:01.515 952-1582/? D/Camera3-Device: clearStreamingRequest: mInterfaceLock.lock() 2020-04-22 16:41:01.515 952-1582/? D/Camera3-Device: clearStreamingRequest: mInterfaceLock.unlock() 2020-04-22 16:41:01.541 1449-1834/? D/BatteryService: Sending ACTION_BATTERY_CHANGED. scale:100, info:{.chargerAcOnline = false, .chargerUsbOnline = true, .chargerWirelessOnline = false, .maxChargingCurrent = 0, .maxChargingVoltage = 0, .batteryStatus = CHARGING, .batteryHealth = GOOD, .batteryPresent = true, .batteryLevel = 27, .batteryVoltage = 3714, .batteryTemperature = 217, .batteryCurrent = 0, .batteryCycleCount = 0, .batteryFullCharge = 7040000, .batteryChargeCounter = 1865600, .batteryTechnology = Li-ion} 2020-04-22 16:41:01.541 1449-1834/? D/BatteryService: online:4, current avg:-319, charge type:2, POGO powered:false, power sharing:false, high voltage charger:false, charger_type:0, capacity:280000, batterySWSelfDischarging:false, misc_event:0, current_event:0, current_now:-457 2020-04-22 16:41:01.541 1449-1449/? D/SamsungPhoneWindowManager: ACTION_BATTERY_CHANGED - Level :: 27, battStatus :: 2 2020-04-22 16:41:01.542 1449-1449/? D/UsbDeviceManager: received ACTION_BATTERY_CHANGED 2020-04-22 16:41:01.542 1449-1505/? D/UsbDeviceManager: handleMessage -> MSG_UPDATE_CHARGING_STATE = 1 2020-04-22 16:41:01.542 1449-1505/? D/UsbDeviceManager: updateUsbNotification(false) : mConnected = true, mConfigured = true, mCurrentFunctions = 5, mHostConnected = false, mSourcePower = false, mSinkPower = true, mUsbCharging = true, mUseUsbNotification = true 2020-04-22 16:41:01.542 1449-1505/? D/UsbDeviceManager: updateUsbNotification, set mCurrentFunctionsForNoti : mtp 2020-04-22 16:41:01.542 1449-1505/? D/DexObserverFW: isDexModeOn() : false 2020-04-22 16:41:01.542 1449-1505/? D/DexObserverFW: isSemiDexModeOn() : false 2020-04-22 16:41:01.542 1449-1505/? D/UsbDeviceManager: id=27, mUsbNotificationId=27, titleRes=17042150 2020-04-22 16:41:01.543 1786-1786/? D/BigDataInfoController: Intent : android.intent.action.BATTERY_CHANGED 2020-04-22 16:41:01.543 1905-1905/? D/KeyguardUpdateMonitor: received broadcast android.intent.action.BATTERY_CHANGED 2020-04-22 16:41:01.543 1905-1905/? D/PowerUI: priorPlugType = 2 mPlugType = 2 priorBatteryStatus = 2 mBatteryStatus = 2 2020-04-22 16:41:01.543 1905-1905/? D/PowerUI.Notification: showChargingNotice oldChargingType : 5 currentChargingType : 5 oldChargingTime : 0 mChargingTime : 0 2020-04-22 16:41:01.543 1905-1905/? D/PowerUI.Notification: There is no change about charging status, so return! 2020-04-22 16:41:01.543 1905-1905/? D/PowerUI: Battery swelling mode - priorBatterySwellingMode = 0 mBatterySwellingMode = 0 2020-04-22 16:41:01.543 1905-1905/? D/PowerUI: showing power sharing dialogfalse 2020-04-22 16:41:01.543 1905-1905/? D/KeyguardUpdateMonitor: handleBatteryUpdate 2020-04-22 16:41:01.547 1905-1905/? D/QSContainerImpl: onMeasure isLandscape? true getDisplayHeight()= 1600 2020-04-22 16:41:01.548 1905-1905/? I/StackScroller: ADJUST STACK.TRANSLATION 2020-04-22 16:41:01.548 1905-1905/? I/chatty: uid=10020(com.samsung.desktopsystemui) com.android.systemui identical 1 line 2020-04-22 16:41:01.548 1905-1905/? I/StackScroller: ADJUST STACK.TRANSLATION 2020-04-22 16:41:01.561 952-4575/? D/Camera3-Status: markComponent: Component 1 is now idle 2020-04-22 16:41:01.579 16932-16949/? E/CameraV2Controller: onImageAvailable width :320 height 240 2020-04-22 16:41:01.580 16932-16949/? E/SmartFaceService: Orientation: 1 2020-04-22 16:41:01.580 16932-16949/? E/SmartFaceService: Calling onPreviewFrame: Thread[Stay/Rotation Worker,5,main]/284046122 2020-04-22 16:41:01.610 3223-3581/? D/SDHMS:s: SIOP:: AP:236(237,0) BAT:217(217,0) CHG:257(257,0) USB:0(0,0) PA:174(224,50) LRP:220(220,-999) ATC:0(0,-999) BLK:231(231,0) LRF:214(214) LRB:220(220) 2020-04-22 16:41:01.646 16932-16949/? E/CameraV2Controller: onImageAvailable width :320 height 240 2020-04-22 16:41:01.647 16932-16949/? E/SmartFaceService: Orientation: 1 2020-04-22 16:41:01.647 16932-16949/? E/SmartFaceService: Calling onPreviewFrame: Thread[Stay/Rotation Worker,5,main]/284046190 2020-04-22 16:41:01.699 1449-1791/? I/Pageboost: Launcher App Execution 2020-04-22 16:41:01.713 16932-16949/? E/CameraV2Controller: onImageAvailable width :320 height 240 2020-04-22 16:41:01.714 16932-16949/? E/SmartFaceService: Orientation: 1 2020-04-22 16:41:01.714 16932-16949/? E/SmartFaceService: Calling onPreviewFrame: Thread[Stay/Rotation Worker,5,main]/284046256 2020-04-22 16:41:01.781 16932-16949/? E/CameraV2Controller: onImageAvailable width :320 height 240 2020-04-22 16:41:01.781 16932-16949/? E/SmartFaceService: Orientation: 1 2020-04-22 16:41:01.781 16932-16949/? E/SmartFaceService: Calling onPreviewFrame: Thread[Stay/Rotation Worker,5,main]/284046324 2020-04-22 16:41:01.809 779-3955/? I/sensors-hal: handle_sns_std_sensor_event:92, [SSC_LIGHT] ambient_light lux: 25, ts=717626898743433 2020-04-22 16:41:01.809 952-1923/? D/CameraService: BasicClient::disconnect E 2020-04-22 16:41:01.809 952-1923/? D/CameraService: BasicClient::disconnect X - already disconnected 2020-04-22 16:41:01.809 952-1923/? I/Camera2ClientBase: Closed Camera 1. Client was: com.samsung.android.smartface (PID 16932, UID 1000) 2020-04-22 16:41:01.847 952-1099/? D/Camera3-Status: markComponent: Component 2 is now idle 2020-04-22 16:41:01.847 952-1099/? D/Camera3-Status: markComponent: Component 0 is now idle 2020-04-22 16:41:01.847 952-1582/? D/Camera3-Device: waitUntilDrained: mInterfaceLock.unlock() 2020-04-22 16:41:01.847 952-1582/? D/Camera3-Device: disconnect: mInterfaceLock.lock() 2020-04-22 16:41:01.847 952-1582/? I/Camera3-Device: disconnect: E 2020-04-22 16:41:01.847 952-1582/? I/CameraLatencyHistogram: ProcessCaptureRequest latency histogram (10) samples: 2020-04-22 16:41:01.847 952-1582/? I/CameraLatencyHistogram: 40 80 120 160 200 240 280 320 360 inf (max ms) 2020-04-22 16:41:01.847 952-1582/? I/CameraLatencyHistogram: 40.00 40.00 0.00 20.00 0.00 0.00 0.00 0.00 0.00 0.00 (%) 2020-04-22 16:41:01.848 16932-16949/? E/CameraV2Controller: onImageAvailable width :320 height 240 2020-04-22 16:41:01.848 16932-16949/? E/SmartFaceService: Orientation: 1 2020-04-22 16:41:01.848 16932-16949/? E/SmartFaceService: Calling onPreviewFrame: Thread[Stay/Rotation Worker,5,main]/284046391 2020-04-22 16:41:01.909 789-12260/? I/SS_3A: INFO: AEC: TsAECDestroy: 257: overrideCameraClose in AECDestroy Camera close mask 7 2020-04-22 16:41:01.909 789-12260/? I/SS_3A: INFO: AEC: TsAECDestroy: 263: pCameraInfo->cameraid in AECDestroy 1 2020-04-22 16:41:01.909 789-12260/? I/SS_3A: INFO: AEC: TsAECDestroy: 285: TsAECDestroy (refcnt:1 cameraId 1 overrideCameraClose 1 lensFacing: 0 pAECAlgorithm 0xee576168 pAECSingletonWrapper->pAECSingleton 0xee576168 2020-04-22 16:41:01.909 789-12260/? I/SS_3A: INFO: AEC: TsAECDestroy: 294: [0]pAECSingleton destroy is 0xee576168 0xee576168 2020-04-22 16:41:01.909 789-12260/? D/SensorListener: sensor_listener_unload is called! 2020-04-22 16:41:01.909 789-12260/? D/SensorListener: sensorListenerUnload is called! m_sensorListener(0xee57ce00) 2020-04-22 16:41:01.909 789-12260/? D/SensorListener: ~SensorListener is called! 2020-04-22 16:41:01.909 789-12260/? I/SS_3A: INFO: AEC: TsAecFront_unload_lib: 160: TsAec_libptr=0x54e2eb67 2020-04-22 16:41:01.910 789-12260/? I/SS_3A: INFO: AWB: TsAwbDestroy: 271: overrideCameraClose in AWBDestroy 7 2020-04-22 16:41:01.910 789-12260/? I/SS_3A: INFO: AWB: TsAwbDestroy: 277: cameraId in AWBDestroy 1 2020-04-22 16:41:01.910 789-12260/? I/SS_3A: INFO: AWB: TsAwbDestroy: 299: TsAwbDestroy (refcnt:1 cameraId 1 overrideCameraClose 1 lensFacing 0 pAWBSingletonWrapper->pAWB 0xee576190 2020-04-22 16:41:01.910 789-12260/? I/SS_3A: INFO: AWB: TsAwbDestroy: 307: Destroy AWBAlgorithm, pAWB 0xee576190 2020-04-22 16:41:01.910 789-12260/? I/SS_3A: INFO: AF: TsAFDestroy: 235: Destroy (refcnt:1 cameraId 1 overrideCameraClose 1 lensFacing 0 pAFAlgorithm 0xee5765c8 pAFSingletonWrapper->pAFSingleton 0xee5765c8 2020-04-22 16:41:01.910 789-12260/? I/SS_3A: INFO: AF: TsAf_deinit_front: 2523: TsAf_wrap: 0xe9b640c0, E 2020-04-22 16:41:01.910 789-12260/? I/SS_3A: INFO: AF: TsAf_deinit_front: 2541: X 2020-04-22 16:41:01.914 789-12260/? D/GTMDRV: [gtmdrv] DeinitGtmdrv phandler addr: 0xeaa5d400 2020-04-22 16:41:01.914 789-12260/? D/GTMDRV: [gtmdrv] DeinitGtmdrv found addr: 0xeaa5d400, index = 0 2020-04-22 16:41:01.928 952-1582/? I/CameraLatencyHistogram: Stream 0 dequeueBuffer latency histogram (10) samples: 2020-04-22 16:41:01.928 952-1582/? I/CameraLatencyHistogram: 5 10 15 20 25 30 35 40 45 inf (max ms) 2020-04-22 16:41:01.928 952-1582/? I/CameraLatencyHistogram: 100.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 0.00 (%) 2020-04-22 16:41:01.929 952-1582/? I/Camera3-Device: disconnect: X 2020-04-22 16:41:01.929 952-1582/? D/Camera3-Device: disconnect: mInterfaceLock.unlock() 2020-04-22 16:41:01.929 952-1582/? D/Camera3-Device: disconnect: mInterfaceLock.lock() 2020-04-22 16:41:01.929 952-1582/? I/Camera3-Device: disconnect: E 2020-04-22 16:41:01.929 952-1582/? D/Camera3-Device: disconnect: mInterfaceLock.unlock() 2020-04-22 16:41:01.930 952-1582/? D/CameraService: BasicClient::disconnect E 2020-04-22 16:41:01.930 952-1582/? I/CameraService: finishCameraOps: Finish camera ops, package name = com.samsung.android.smartface, client UID = 1000 2020-04-22 16:41:01.930 3223-28935/? I/CameraManagerGlobal: postSingleUpdate device: camera id 1 status STATUS_PRESENT 2020-04-22 16:41:01.931 952-1582/? I/CameraFlashlight: deviceClosed: device 1 is closed(package=com.samsung.android.smartface) 2020-04-22 16:41:01.931 3223-3223/? D/SDHMS:g: writeSysfs:: path = /sys/devices/system/cpu/cpu0/cpufreq/schedutil/down_rate_limit_us, value = 0 2020-04-22 16:41:01.932 3223-3223/? D/SDHMS:g: writeSysfs:: path = /sys/devices/system/cpu/cpu4/cpufreq/schedutil/down_rate_limit_us, value = 0 2020-04-22 16:41:01.932 952-1582/? I/CameraFlashlight: Skip deviceClosed for smart stay 2020-04-22 16:41:01.932 952-1582/? I/CameraService: disconnect: Disconnected client for camera 1 for PID 16932 2020-04-22 16:41:01.932 3223-3223/? D/SDHMS:g: writeSysfs:: path = /sys/devices/system/cpu/cpu7/cpufreq/schedutil/down_rate_limit_us, value = 0 2020-04-22 16:41:01.932 952-1582/? D/CameraService: BasicClient::disconnect X 2020-04-22 16:41:01.933 16932-16948/? E/CameraV2Controller: handleMessage signal: 0 2020-04-22 16:41:01.933 16932-16948/? E/CameraV2Controller: handleMessage(0) before let go!{ when=-422ms what=0 target=com.samsung.android.smartface.CameraV2Controller$EventHandler } 2020-04-22 16:41:01.933 16932-16948/? E/CameraV2Controller: handleMessage(0) after let go!{ when=-422ms what=0 target=com.samsung.android.smartface.CameraV2Controller$EventHandler } 2020-04-22 16:41:01.933 16932-16948/? E/CameraV2Controller: handleMessage, lock.unlock before 2020-04-22 16:41:01.933 16932-16948/? E/CameraV2Controller: handleMessage(0) { when=-422ms what=0 target=com.samsung.android.smartface.CameraV2Controller$EventHandler } 2020-04-22 16:41:01.933 16932-11428/? E/CameraController: waitHere, 422ms 2020-04-22 16:41:01.933 16932-11428/? E/CameraController: Wait for 0 DONE! 2020-04-22 16:41:01.933 16932-11428/? E/CameraController: sendMessageAndWait, lock.unlock before 2020-04-22 16:41:01.933 16932-11428/? E/CameraController: sendMessageAndWait, lock.unlock after 2020-04-22 16:41:01.933 16932-11428/? E/CameraController: closeCamera E 2020-04-22 16:41:01.934 16932-11428/? D/SmartFaceIndicator: no icon 2020-04-22 16:41:01.935 16932-11428/? E/SmartFaceService: mWakeLock.release() in FD 2020-04-22 16:41:01.935 1449-4572/? E/SmartFaceManager: Listener does not implements SmartFaceInfoListener2 2020-04-22 16:41:01.935 1449-1761/? E/SmartFaceManager: checkForSmartStay X: false 2020-04-22 16:41:01.935 1449-1761/? D/PowerManagerService: faceDetect : bFaceDetected : false 2020-04-22 16:41:01.935 1449-1761/? D/PowerManagerService: handleSmartStay(preTest) : sendBroadcast done.

aeroxr1 commented 4 years ago

Seems the crash is related to a CAST. In my query I have different cast like this: CAST ( json_extract(People.json, '$.entity.base.name') AS TEXT) I found that if in my query the word "text" is written in uppercase the sqlcipher will crash, instead if it is written in lowerCase it works. Why ?

developernotes commented 4 years ago

Hi @aeroxr1

I put together the following test which is successful within the SQLCipher for Android test suite using both TEXT and text. Please post below if you are performing something different. If so, are you able to create a reproducible test case runnable within the test suite?

package net.zetetic.tests;

import net.sqlcipher.Cursor;
import net.sqlcipher.database.SQLiteDatabase;

public class JsonCastTest extends SQLCipherTest {
  @Override
  public boolean execute(SQLiteDatabase database) {
    String name = "Bob Smith", queryName = "";
    String query = String.format("select cast(json_extract('{\"user\":\"%s\"}','$.user') as TEXT);", name);
    Cursor cursor = database.rawQuery(query, new Object[]{});
    if(cursor != null && cursor.moveToFirst()){
      queryName = cursor.getString(0);
      cursor.close();
    }
    return name.equals(queryName);
  }

  @Override
  public String getName() {
    return "JSON cast test";
  }
}
aeroxr1 commented 4 years ago

Hi, tomorrow I will continue to investigate. The only thing that I know is that I have extrapolated this WHERE condiction from the big original query: WHERE ( CAST (json_extract([utente].[json], '$.id') AS TEXT) IN (2,4)) and I have replicated this in a simple query. While inside the big query the behaviour between write "TEXT" in uppercase or in lowercase make the difference between crash or not, in the simple query there aren't difference between lower or upper case and the test not crash. From the ndk-stack can you see something ? Sadly also the test crash and the logcat quickly disappears :/

aeroxr1 commented 4 years ago

Ok, changing from TEXT to text causes a different query plan. I have downloaded the last https://sqlitebrowser.org/ version and also the sqlitebrowser crashes on the same db executing the same query (before upgrading I was using SQLLiteBrowser Version 3.10.1 with SQLCipher Version 3.15.2 and with that version there is no crash).

Is there a way I can share with you my db with my test query? Thanks for the support

developernotes commented 4 years ago

Hello @aeroxr1

You can fork the SQLCipher for Android test suite, create a sample reproduction case following these instructions, push up your changes, and then provide the URL to your repository for review. Would you give that a try? Thanks!

aeroxr1 commented 4 years ago

Hi ! Thanks I'm working on it :)

aeroxr1 commented 4 years ago

Hello @aeroxr1

You can fork the SQLCipher for Android test suite, create a sample reproduction case following these instructions, push up your changes, and then provide the URL to your repository for review. Would you give that a try? Thanks!

Ok, this is the repository url: https://github.com/aeroxr1/sqlcipher-android-tests Thanks !

developernotes commented 4 years ago

Hello @aeroxr1

This issue is not isolated to SQLCipher specifically, I have verified it also fails from the SQLite command line shell using version 3.31.0. You may wish to report this to the upstream SQLite team for further information. Additionally, I was able to capture the crash in SQLite 3.31.0 using lldb when executing your query. The output is as follows: 

Process 30329 stopped
* thread #1, queue = 'com.apple.main-thread', stop reason = EXC_BAD_ACCESS (code=1, address=0x54)
    frame #0: 0x00000001000dcaf2 sqlite3`isAuxiliaryVtabOperator(db=0x0000000100604080, pExpr=0x0000000108016030, peOp2="", ppLeft=0x00007ffeefbfcb00, ppRight=0x00007ffeefbfcb08) at sqlite3.c:142621:33
   142618       int res = 0;
   142619       Expr *pLeft = pExpr->pLeft;
   142620       Expr *pRight = pExpr->pRight;
-> 142621       if( pLeft->op==TK_COLUMN && IsVirtual(pLeft->y.pTab) ){
   142622         res++;
   142623       }
   142624       if( pRight && pRight->op==TK_COLUMN && IsVirtual(pRight->y.pTab) ){
Target 0: (sqlite3) stopped.

Also, this was the following backtrace from that query execution:

* thread #1, queue = 'com.apple.main-thread', stop reason = EXC_BAD_ACCESS (code=1, address=0x54)
  * frame #0: 0x00000001000dcaf2 sqlite3`isAuxiliaryVtabOperator(db=0x0000000100604080, pExpr=0x0000000108016030, peOp2="", ppLeft=0x00007ffeefbfcb00, ppRight=0x00007ffeefbfcb08) at sqlite3.c:142621:33
    frame #1: 0x00000001000daad1 sqlite3`exprAnalyze(pSrc=0x000000010800f080, pWC=0x000000010800ec38, idxTerm=0) at sqlite3.c:143483:15
    frame #2: 0x00000001000d4bb7 sqlite3`sqlite3WhereExprAnalyze(pTabList=0x000000010800f080, pWC=0x000000010800ec38) at sqlite3.c:143757:5
    frame #3: 0x00000001000cce1e sqlite3`sqlite3WhereBegin(pParse=0x00007ffeefbfe5d8, pTabList=0x000000010800f080, pWhere=0x00000001080158b0, pOrderBy=0x0000000000000000, pResultSet=0x0000000000000000, wctrlFlags=32, iAuxArg=4) at sqlite3.c:148582:3
    frame #4: 0x00000001000d9263 sqlite3`sqlite3WhereCodeOneLoopStart(pParse=0x00007ffeefbfe5d8, v=0x00000001080136d0, pWInfo=0x0000000104808c00, iLevel=1, pLevel=0x0000000104808ff8, notReady=18446744073709551614) at sqlite3.c:141890:21
    frame #5: 0x00000001000cdc3e sqlite3`sqlite3WhereBegin(pParse=0x00007ffeefbfe5d8, pTabList=0x0000000108013b80, pWhere=0x0000000108014ab0, pOrderBy=0x0000000000000000, pResultSet=0x0000000108017230, wctrlFlags=0, iAuxArg=320) at sqlite3.c:148972:16
    frame #6: 0x00000001000a1965 sqlite3`sqlite3Select(pParse=0x00007ffeefbfe5d8, p=0x0000000108014b30, pDest=0x00007ffeefbfda98) at sqlite3.c:134063:14
    frame #7: 0x0000000100098189 sqlite3`yy_reduce(yypParser=0x00007ffeefbfdbc0, yyruleno=82, yyLookahead=1, yyLookaheadToken=(z = ";", n = 1), pParse=0x00007ffeefbfe5d8) at sqlite3.c:155869:3
    frame #8: 0x00000001000973c0 sqlite3`sqlite3Parser(yyp=0x00007ffeefbfdbc0, yymajor=1, yyminor=(z = ";", n = 1)) at sqlite3.c:157163:15
    frame #9: 0x0000000100043d45 sqlite3`sqlite3RunParser(pParse=0x00007ffeefbfe5d8, zSql=";", pzErrMsg=0x00007ffeefbfe780) at sqlite3.c:158437:5
    frame #10: 0x000000010008e688 sqlite3`sqlite3Prepare(db=0x0000000100604080, zSql="SELECT      CAST(json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) AS remoteid,\nCAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT) as customerId,\nCAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) as custmerLocalId,\nCAST (json_extract([Quote].[json], '$.localLinks.\"entity.customer.customerId\"') AS TEXT) as localLinksCustomerID\nFROM      [a_customerbase]\nLEFT JOIN Quote\nON        (( CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) IS NOT NULL\nAND CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) = CAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT))\nOR\nCAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) = CAST (json_extract([Quote].[json], '$.localLinks.\"entity.customer.customerId\"') AS TEXT))\n\nWHERE     CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) in (4832);", nBytes=-1, prepFlags=128, pReprepare=0x0000000000000000, ppStmt=0x00007ffeefbfe8e8, pzTail=0x00007ffeefbfe8d8) at sqlite3.c:127557:5
    frame #11: 0x0000000100042c30 sqlite3`sqlite3LockAndPrepare(db=0x0000000100604080, zSql="SELECT      CAST(json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) AS remoteid,\nCAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT) as customerId,\nCAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) as custmerLocalId,\nCAST (json_extract([Quote].[json], '$.localLinks.\"entity.customer.customerId\"') AS TEXT) as localLinksCustomerID\nFROM      [a_customerbase]\nLEFT JOIN Quote\nON        (( CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) IS NOT NULL\nAND CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) = CAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT))\nOR\nCAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) = CAST (json_extract([Quote].[json], '$.localLinks.\"entity.customer.customerId\"') AS TEXT))\n\nWHERE     CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) in (4832);", nBytes=-1, prepFlags=128, pOld=0x0000000000000000, ppStmt=0x00007ffeefbfe8e8, pzTail=0x00007ffeefbfe8d8) at sqlite3.c:127629:10
    frame #12: 0x0000000100038386 sqlite3`sqlite3_prepare_v2(db=0x0000000100604080, zSql="SELECT      CAST(json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) AS remoteid,\nCAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT) as customerId,\nCAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) as custmerLocalId,\nCAST (json_extract([Quote].[json], '$.localLinks.\"entity.customer.customerId\"') AS TEXT) as localLinksCustomerID\nFROM      [a_customerbase]\nLEFT JOIN Quote\nON        (( CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) IS NOT NULL\nAND CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) = CAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT))\nOR\nCAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) = CAST (json_extract([Quote].[json], '$.localLinks.\"entity.customer.customerId\"') AS TEXT))\n\nWHERE     CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) in (4832);", nBytes=-1, ppStmt=0x00007ffeefbfe8e8, pzTail=0x00007ffeefbfe8d8) at sqlite3.c:127713:8
    frame #13: 0x00000001000128fe sqlite3`shell_exec(pArg=0x00007ffeefbfeb98, zSql="SELECT      CAST(json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) AS remoteid,\nCAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT) as customerId,\nCAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) as custmerLocalId,\nCAST (json_extract([Quote].[json], '$.localLinks.\"entity.customer.customerId\"') AS TEXT) as localLinksCustomerID\nFROM      [a_customerbase]\nLEFT JOIN Quote\nON        (( CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) IS NOT NULL\nAND CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) = CAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT))\nOR\nCAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) = CAST (json_extract([Quote].[json], '$.localLinks.\"entity.customer.customerId\"') AS TEXT))\n\nWHERE     CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) in (4832);", pzErrMsg=0x00007ffeefbfe950) at shell.c:11614:10
    frame #14: 0x0000000100030272 sqlite3`runOneSqlLine(p=0x00007ffeefbfeb98, zSql="SELECT      CAST(json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) AS remoteid,\nCAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT) as customerId,\nCAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) as custmerLocalId,\nCAST (json_extract([Quote].[json], '$.localLinks.\"entity.customer.customerId\"') AS TEXT) as localLinksCustomerID\nFROM      [a_customerbase]\nLEFT JOIN Quote\nON        (( CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) IS NOT NULL\nAND CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) = CAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT))\nOR\nCAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) = CAST (json_extract([Quote].[json], '$.localLinks.\"entity.customer.customerId\"') AS TEXT))\n\nWHERE     CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) in (4832);", in=0x0000000000000000, startline=1) at shell.c:18294:8
    frame #15: 0x0000000100013912 sqlite3`process_input(p=0x00007ffeefbfeb98) at shell.c:18394:17
    frame #16: 0x0000000100008564 sqlite3`main(argc=2, argv=0x00007ffeefbff4b8) at shell.c:19152:12
    frame #17: 0x00007fff68f4ecc9 libdyld.dylib`start + 1
    frame #18: 0x00007fff68f4ecc9 libdyld.dylib`start + 1

I am going to close this issue as it is not specific to SQLCipher for Android. Thanks!

aeroxr1 commented 4 years ago

So you don't know if this could be something wrong with my db/query or a sqllite bug? Do you think that the lowercase "text" Is Just a side effect? I'm going to follow your suggestions,thanks for your support

developernotes commented 4 years ago

Hi @aeroxr1

Because it is causing a crash in standard SQLite, it would be best to report it upstream to get their feedback. Good luck!

brodybits commented 4 years ago

@developernotes would you mind giving me some instructions how to reproduce this on SQLite 3.31.0?

I would love to see this crash fixed upstream, and would love to help see this happen, would be easier if I don't have to build the custom test app.

developernotes commented 4 years ago

Hi @brodybits

You must build SQLite with JSON support (i.e., -DSQLITE_ENABLE_JSON1). You will see the crash in SQLite by opening the database (i.e., dbTest.db) used in this repo with the command line shell. Then execute the following query:

SELECT      CAST(json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) AS remoteid,
CAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT) as customerId,
CAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) as custmerLocalId,
CAST (json_extract([Quote].[json], '$.localLinks."entity.customer.customerId"') AS TEXT) as localLinksCustomerID
FROM      [a_customerbase]
LEFT JOIN Quote
ON        (( CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) IS NOT NULL
AND CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) = CAST (json_extract([Quote].[json], '$.entity.customer.customerId') AS TEXT))
OR
CAST (json_extract([a_customerbase].[json], '$.localId') AS TEXT) = CAST (json_extract([Quote].[json], '$.localLinks."entity.customer.customerId"') AS TEXT))

WHERE     CAST (json_extract([a_customerbase].[json], '$.remoteId') AS TEXT) in (4832);
brodybits commented 4 years ago

the database (i.e., dbTest.db) used in this repo

https://github.com/aeroxr1/sqlcipher-android-tests/blob/master/app/src/main/assets/dbTest.db

Does not crash for me on the most recent snapshot from https://www.sqlite.org/download.html. I will try with SQLite 3.31.1 next.

brodybits commented 4 years ago

I do see a crash on SQLite 3.31.1 but as I said before, no crash on the most recent snapshot. I do hope they will release a patch of SQLite in the near future.

In case this is an urgent issue, it is possible though potentially risky to patch SQLCipher yourself: https://discuss.zetetic.net/t/sqlcipher-sqlite-update/2470/11

aeroxr1 commented 4 years ago

Hi @brodybits , thanks for you help. Have you found which bug fix is related to this crash? It would be helpful to have a reference so that I could search for other potentially dangerous queries in my code.

Do you think that I can modify in some way the query to avoid the bug and obtain the same results?

Thank you both for your support.