search

beeware / briefcase

Tools to support converting a Python project into a standalone native application.
https://briefcase.readthedocs.io/
BSD 3-Clause "New" or "Revised" License
2.66k stars 372 forks source link

Unable to run on Android 5.1.1 #538

Closed czoins closed 2 years ago

czoins commented 3 years ago

Describe the bug I am trying to install the tutorial app on Android 5.1.1 (API 22). It installs just fine, and crashes while loading (you can see the splash screen). I haven't modified anything when to comes to settings, and even the app. I have run the app on both Android 6.0 & 5.1.1 emulators, and a physical 5.1.1 device. It runs just fine on 6.0, so it's pretty clear that it has to do with 5.1.1. In build.gradle, minSdkVersion is set to 21, so that's quite odd.

To Reproduce Steps to reproduce the behavior:

  1. Follow the BeeWare tutorial
  2. briefcase run android
  3. Run on Android 5.1.1
  4. Crash

Expected behavior To run on API 21+

Environment:

Additional context Another thing to note, is that you cannot set the target sdk below 28, as i tried setting it to 27 & 26 & 22 and it just throws the same errors about linking.

LOG ``` --------- beginning of crash F/libc ( 2631): Fatal signal 6 (SIGABRT), code -6 in tid 2631 (mple.helloworld) W/linker ( 2659): libdB3A84527CEF4.so: unused DT entry: type 0x6ffffef5 arg 0x6c0 W/linker ( 2659): libdB3A84527CEF4.so: unused DT entry: type 0x6ffffffe arg 0x680 W/linker ( 2659): libdB3A84527CEF4.so: unused DT entry: type 0x6fffffff arg 0x2 I/ExposureNotification( 1717): ThreadSafeLevelDbWrapper: do open LevelDb en-matching-request-db [CONTEXT service_id=236 ] I/ExposureNotification( 1717): ThreadSafeLevelDbWrapper:en-matching-request-db instance aimh@8a7297d created [CONTEXT service_id=236 ] I/ExposureNotification( 1717): ThreadSafeLevelDbWrapper:en-matching-request-db instance aimh@8a7297d closed [CONTEXT service_id=236 ] I/ExposureNotification( 1717): ThreadSafeLevelDbWrapper: do close LevelDb en-matching-request-db [CONTEXT service_id=236 ] I/NetRec ( 1717): [77] BootCompletedOrAppUpdatedIntentOperation.onHandleIntent: onBootCompleted() I/ExposureNotification( 1717): ExposureServiceInternalService.onDestroy, com.google.android.gms.nearby.exposurenotification.service.ExposureNotificationInternalChimeraService@16c2dae0 [CONTEXT service_id=236 ] I/DEBUG ( 1143): *** *** *** *** *** *** *** *** *** *** *** *** *** *** *** *** I/DEBUG ( 1143): Build fingerprint: 'Android/sdk_google_phone_x86/generic_x86:5.1.1/LMY48X/6695563:userdebug/test-keys' I/DEBUG ( 1143): Revision: '0' I/DEBUG ( 1143): ABI: 'x86' I/DEBUG ( 1143): pid: 2631, tid: 2631, name: mple.helloworld >>> com.example.helloworld <<< I/DEBUG ( 1143): signal 6 (SIGABRT), code -6 (SI_TKILL), fault addr -------- I/DEBUG ( 1143): Abort message: 'art/runtime/check_jni.cc:65] JNI DETECTED ERROR IN APPLICATION: JNI NewGlobalRef called with pending exception 'java.lang.ClassNotFoundException' thrown in unknown throw location' I/DEBUG ( 1143): eax 00000000 ebx 00000a47 ecx 00000a47 edx 00000006 I/DEBUG ( 1143): esi b77cfc48 edi 0000000b I/DEBUG ( 1143): xcs 00000073 xds 0000007b xes 0000007b xfs 00000007 xss 0000007b I/DEBUG ( 1143): eip b755d2e6 ebp 00000a47 esp bf96dcd0 flags 00000286 I/DEBUG ( 1143): I/DEBUG ( 1143): backtrace: I/DEBUG ( 1143): #00 pc 000742e6 /system/lib/libc.so (tgkill+22) I/DEBUG ( 1143): #01 pc 0002217b /system/lib/libc.so (pthread_kill+155) I/DEBUG ( 1143): #02 pc 000239f4 /system/lib/libc.so (raise+36) I/DEBUG ( 1143): #03 pc 0001bdf4 /system/lib/libc.so (abort+84) I/DEBUG ( 1143): #04 pc 00377f56 /system/lib/libart.so (art::Runtime::Abort()+294) I/DEBUG ( 1143): #05 pc 000ec704 /system/lib/libart.so (art::LogMessage::~LogMessage()+1876) I/DEBUG ( 1143): #06 pc 00102c53 /system/lib/libart.so (art::JniAbort(char const*, char const*)+1683) I/DEBUG ( 1143): #07 pc 0010359c /system/lib/libart.so (art::JniAbortF(char const*, char const*, ...)+108) I/DEBUG ( 1143): #08 pc 00109663 /system/lib/libart.so (art::ScopedCheck::CheckThread(int)+2867) I/DEBUG ( 1143): #09 pc 00109e42 /system/lib/libart.so (art::ScopedCheck::ScopedCheck(_JNIEnv*, int, char const*)+322) I/DEBUG ( 1143): #10 pc 001161fb /system/lib/libart.so (art::CheckJNI::NewGlobalRef(_JNIEnv*, _jobject*)+59) I/DEBUG ( 1143): #11 pc 00003619 /data/app/com.example.helloworld-1/lib/x86/librubicon.so (NewGlobalRef+41) I/DEBUG ( 1143): #12 pc 00008fc7 /data/app/com.example.helloworld-1/lib/x86/libffi.so I/DEBUG ( 1143): #13 pc 00008420 /data/app/com.example.helloworld-1/lib/x86/libffi.so I/DEBUG ( 1143): #14 pc 00007ffb /data/app/com.example.helloworld-1/lib/x86/libffi.so (ffi_call+139) I/DEBUG ( 1143): #15 pc 00016744 /data/data/com.example.helloworld/files/python/stdlib/lib/python3.7/lib-dynload/_ctypes.cpython-37m.so (_call_function_pointer+452) I/DEBUG ( 1143): #16 pc 0001605a /data/data/com.example.helloworld/files/python/stdlib/lib/python3.7/lib-dynload/_ctypes.cpython-37m.so (_ctypes_callproc+1226) I/DEBUG ( 1143): #17 pc 0000858b /data/data/com.example.helloworld/files/python/stdlib/lib/python3.7/lib-dynload/_ctypes.cpython-37m.so (PyCFuncPtr_call+907) I/DEBUG ( 1143): #18 pc 0006ebe2 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyObject_FastCallKeywords+850) I/DEBUG ( 1143): #19 pc 001dd065 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #20 pc 001d7cbf /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyEval_EvalFrameDefault+40447) I/DEBUG ( 1143): #21 pc 001cdeab /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (PyEval_EvalFrameEx+107) I/DEBUG ( 1143): #22 pc 001de8f4 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyEval_EvalCodeWithName+5044) I/DEBUG ( 1143): #23 pc 0006e654 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyFunction_FastCallDict+1172) I/DEBUG ( 1143): #24 pc 0006deed /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyObject_FastCallDict+109) I/DEBUG ( 1143): #25 pc 00070f2c /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyObject_Call_Prepend+252) I/DEBUG ( 1143): #26 pc 00103808 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #27 pc 000fa43e /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #28 pc 0006ebe2 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyObject_FastCallKeywords+850) I/DEBUG ( 1143): #29 pc 001dd065 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #30 pc 001d7da7 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyEval_EvalFrameDefault+40679) I/DEBUG ( 1143): #31 pc 001cdeab /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (PyEval_EvalFrameEx+107) I/DEBUG ( 1143): #32 pc 001de8f4 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyEval_EvalCodeWithName+5044) I/DEBUG ( 1143): #33 pc 001cddf2 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (PyEval_EvalCodeEx+354) I/DEBUG ( 1143): #34 pc 001cdc7b /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (PyEval_EvalCode+155) I/DEBUG ( 1143): #35 pc 001c97e9 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #36 pc 001c69c3 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #37 pc 0006fc82 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyMethodDef_RawFastCallDict+994) I/DEBUG ( 1143): #38 pc 0006e798 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyCFunction_FastCallDict+152) I/DEBUG ( 1143): #39 pc 0006f5f5 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (PyCFunction_Call+149) I/DEBUG ( 1143): #40 pc 001dd361 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #41 pc 001d8277 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyEval_EvalFrameDefault+41911) I/DEBUG ( 1143): #42 pc 001cdeab /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (PyEval_EvalFrameEx+107) I/DEBUG ( 1143): #43 pc 001de8f4 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyEval_EvalCodeWithName+5044) I/DEBUG ( 1143): #44 pc 0006f0b8 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyFunction_FastCallKeywords+824) I/DEBUG ( 1143): #45 pc 001dd02f /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #46 pc 001d7cbf /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyEval_EvalFrameDefault+40447) I/DEBUG ( 1143): #47 pc 001cdeab /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (PyEval_EvalFrameEx+107) I/DEBUG ( 1143): #48 pc 0006f743 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #49 pc 0006ee8f /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyFunction_FastCallKeywords+271) I/DEBUG ( 1143): #50 pc 001dd02f /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #51 pc 001d7d1c /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyEval_EvalFrameDefault+40540) I/DEBUG ( 1143): #52 pc 001cdeab /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (PyEval_EvalFrameEx+107) I/DEBUG ( 1143): #53 pc 0006f743 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #54 pc 0006ee8f /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyFunction_FastCallKeywords+271) I/DEBUG ( 1143): #55 pc 001dd02f /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #56 pc 001d7da7 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyEval_EvalFrameDefault+40679) I/DEBUG ( 1143): #57 pc 001cdeab /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (PyEval_EvalFrameEx+107) I/DEBUG ( 1143): #58 pc 0006f743 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #59 pc 0006ee8f /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyFunction_FastCallKeywords+271) I/DEBUG ( 1143): #60 pc 001dd02f /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/DEBUG ( 1143): #61 pc 001d7da7 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (_PyEval_EvalFrameDefault+40679) I/DEBUG ( 1143): #62 pc 001cdeab /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so (PyEval_EvalFrameEx+107) I/DEBUG ( 1143): #63 pc 0006f743 /data/app/com.example.helloworld-1/lib/x86/libpython3.7m.so I/NetRec ( 1717): [77] BootCompletedOrAppUpdatedIntentOperation.onHandleIntent: Scheduling CleanupDatabaseTask I/NetRec ( 1717): [77] akvg.a: onConfigChanged() I/NetRec ( 1717): [78] ClearDatabaseService.onHandleIntent: Clearing score cache I/NetRec ( 1717): [77] akvg.a: Cancelling all NetRec GCM tasks D/AlarmManagerService( 1483): Kernel timezone updated to -60 minutes west of GMT D/AlarmManagerService( 1483): Setting time of day to sec=1607028662 I/anxz ( 1717): updateFromConfigurations using legacy put method [CONTEXT service_id=204 ] V/AlarmClock( 2305): AlarmInitReceiver android.intent.action.TIME_SET V/AlarmClock( 2305): AlarmInitReceiver finished I/SystemUpdate( 2059): [Installation,ReceiverIntentOperation] Received intent: Intent { act=android.intent.action.TIME_SET flg=0x24000010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver }. I/anxz ( 1717): updateFromConfigurations using legacy put method [CONTEXT service_id=204 ] I/ChimeraConfigurator( 2059): Starting update, reason: 4 urgentFeatures: pay:-1 I/art ( 2659): Rejecting re-init on previously-failed class java.lang.Class I/art ( 2659): Rejecting re-init on previously-failed class java.lang.Class W/art ( 1938): Suspending all threads took: 19.062ms I/art ( 1938): Explicit concurrent mark sweep GC freed 4143(184KB) AllocSpace objects, 0(0B) LOS objects, 45% free, 1214KB/2MB, paused 20.873ms total 73.252ms I/SystemUpdate( 2059): [Execution,InstallationEventIntentOperation] Handling event of type 6. E/ConfigFileUtils( 2059): Failed to read config file: /data/data/com.google.android.gms/app_chimera/next_container.pb: open failed: ENOENT (No such file or directory) I/rcs ( 1717): [RcsBindingManager] Checking if we should rebind to previous service. Attempt 0 I/rcs ( 1717): [RcsBindingManager] No valid settings for binding. V/SmartDevice( 2059): [service,SmartDeviceIntentHandlers] OnUpgradeOrBootOperation got intent: Intent { act=android.intent.action.BOOT_COMPLETED flg=0x8000010 cmp=com.google.android.gms/.chimera.GmsIntentOperationService (has extras) } with flag 3 I/SystemUpdate( 2059): [Execution,InstallationIntentOperation] Received intent: Intent { act=com.google.android.gms.update.INSTALL_UPDATE cat=[targeted_intent_op_prefix:.update.execution.InstallationIntentOperation] cmp=com.google.android.gms/.chimera.GmsIntentOperationService }. I/SystemUpdate( 2059): [Execution,ExecutionManager] Action finished-execution executed for 0.00 seconds. E/art ( 2059): invalid stream - problem with parameter iterator in /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk for method void ecx.c(java.lang.String, java.lang.String, java.lang.Object[]) E/art ( 2059): invalid stream - problem with parameter iterator in /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk:classes3.dex for method void afuw.c(java.lang.String, java.lang.Object[]) W/MDM ( 2059): [159] afuw.c: Device admin action is a no-op. Action: true W/art ( 2059): Suspending all threads took: 11.176ms I/art ( 2059): Background sticky concurrent mark sweep GC freed 22775(1546KB) AllocSpace objects, 25(400KB) LOS objects, 10% free, 17MB/19MB, paused 18.544ms total 84.342ms D/ ( 2659): HostConnection::get() New Host Connection established 0x9acd9060, tid 2696 D/EGL_emulation( 2659): eglCreateContext: 0xb438d940: maj 2 min 0 rcv 2 D/EGL_emulation( 2659): eglMakeCurrent: 0xb438d940: ver 2 0 (tinfo 0x9aca8350) I/art ( 1717): Background sticky concurrent mark sweep GC freed 37275(2MB) AllocSpace objects, 23(580KB) LOS objects, 11% free, 15MB/17MB, paused 2.212ms total 127.149ms W/art ( 2059): Long monitor contention event with owner method=void ctz.run() from :com.google.android.gms@202414013@20.24.14 (020700-319035315):3 waiters=0 for 322ms I/TelephonySpam( 2059): TelephonySpamInit - On boot completed TelephonySpam module initialized I/TelephonySpam( 2059): TelephonySpamInit - onModuleUpdated TelephonySpam module initialized I/TelephonySpam( 2059): SpamListSync - scheduleSyncTasks I/TelephonySpam( 2059): SpamListSync - schedulePeriodicTask I/BackupAndSyncValidation( 2059): Scheduling the service. I/TpHceSessionManager( 1717): getInstance: new instance created [CONTEXT service_id=79 ] I/atoh ( 1717): Maintaining payment bundles [CONTEXT service_id=79 ] I/atoh ( 1717): Finished maintaining payment bundles [CONTEXT service_id=79 ] W/atfe ( 1717): Overriding default PDOL tags with g services flag: 9f01069f09029f15029f160f9f1c089f1e089f33039f35019f39019f4e20 [CONTEXT service_id=79 ] W/ActivityManager( 1483): Force finishing activity 1 com.example.helloworld/org.beeware.android.MainActivity I/DEBUG ( 1143): I/DEBUG ( 1143): Tombstone written to: /data/tombstones/tombstone_06 I/BootReceiver( 1483): Copying /data/tombstones/tombstone_06 to DropBox (SYSTEM_TOMBSTONE) D/EGL_emulation( 2659): eglCreateContext: 0xb438d8e0: maj 2 min 0 rcv 2 D/EGL_emulation( 2659): eglMakeCurrent: 0xb438d8e0: ver 2 0 (tinfo 0x9aca8350) I/Zygote ( 1150): Process 2631 exited due to signal (6) I/art ( 2059): Explicit concurrent mark sweep GC freed 12501(548KB) AllocSpace objects, 0(0B) LOS objects, 18% free, 17MB/21MB, paused 29.362ms total 309.203ms D/ ( 2659): HostConnection::get() New Host Connection established 0xaf09b510, tid 2740 D/EGL_emulation( 2659): eglCreateContext: 0xaf0b40a0: maj 2 min 0 rcv 2 D/EGL_emulation( 2659): eglMakeCurrent: 0xaf0b40a0: ver 2 0 (tinfo 0xaf039780) I/WindowManager( 1483): Screenshot max retries 4 of Token{65224ac ActivityRecord{ad6025f u0 com.example.helloworld/org.beeware.android.MainActivity t4 f}} appWin=Window{e0208f3 u0 Starting com.example.helloworld} drawState=4 W/ActivityManager( 1483): Exception thrown during pause W/ActivityManager( 1483): android.os.DeadObjectException W/ActivityManager( 1483): at android.os.BinderProxy.transactNative(Native Method) W/ActivityManager( 1483): at android.os.BinderProxy.transact(Binder.java:496) W/ActivityManager( 1483): at android.app.ApplicationThreadProxy.schedulePauseActivity(ApplicationThreadNative.java:704) W/ActivityManager( 1483): at com.android.server.am.ActivityStack.startPausingLocked(ActivityStack.java:825) W/ActivityManager( 1483): at com.android.server.am.ActivityStack.finishActivityLocked(ActivityStack.java:2726) W/ActivityManager( 1483): at com.android.server.am.ActivityStack.finishTopRunningActivityLocked(ActivityStack.java:2583) W/ActivityManager( 1483): at com.android.server.am.ActivityStackSupervisor.finishTopRunningActivityLocked(ActivityStackSupervisor.java:2497) W/ActivityManager( 1483): at com.android.server.am.ActivityManagerService.handleAppCrashLocked(ActivityManagerService.java:11505) W/ActivityManager( 1483): at com.android.server.am.ActivityManagerService.makeAppCrashingLocked(ActivityManagerService.java:11402) W/ActivityManager( 1483): at com.android.server.am.ActivityManagerService.crashApplication(ActivityManagerService.java:12086) W/ActivityManager( 1483): at com.android.server.am.ActivityManagerService.handleApplicationCrashInner(ActivityManagerService.java:11597) W/ActivityManager( 1483): at com.android.server.am.NativeCrashListener$NativeCrashReporter.run(NativeCrashListener.java:86) E/lowmemorykiller( 1133): Error opening /proc/2631/oom_score_adj; errno=2 W/art ( 1483): Long monitor contention event with owner method=void com.android.server.am.ActivityManagerService.crashApplication(com.android.server.am.ProcessRecord, android.app.ApplicationErrorReport$CrashInfo) from ActivityManagerService.java:12032 waiters=1 for 349ms W/art ( 1483): Long monitor contention event with owner method=void com.android.server.am.ActivityManagerService.crashApplication(com.android.server.am.ProcessRecord, android.app.ApplicationErrorReport$CrashInfo) from ActivityManagerService.java:12032 waiters=2 for 350ms W/art ( 1483): Long monitor contention event with owner method=void com.android.server.am.ActivityManagerService.crashApplication(com.android.server.am.ProcessRecord, android.app.ApplicationErrorReport$CrashInfo) from ActivityManagerService.java:12032 waiters=5 for 321ms W/art ( 1483): Long monitor contention event with owner method=void com.android.server.am.ActivityManagerService.crashApplication(com.android.server.am.ProcessRecord, android.app.ApplicationErrorReport$CrashInfo) from ActivityManagerService.java:12032 waiters=7 for 220ms D/Atlas ( 1483): Validating map... W/art ( 2059): Long monitor contention event with owner method=android.content.IContentProvider android.app.ActivityThread.acquireExistingProvider(android.content.Context, java.lang.String, int, boolean) from ActivityThread.java:4710 waiters=1 for 373ms W/art ( 2059): Long monitor contention event with owner method=android.content.IContentProvider android.app.ActivityThread.acquireExistingProvider(android.content.Context, java.lang.String, int, boolean) from ActivityThread.java:4710 waiters=0 for 374ms W/libprocessgroup( 1483): failed to open /acct/uid_10058/pid_2631/cgroup.procs: No such file or directory I/ActivityManager( 1483): Process com.example.helloworld (pid 2631) has died W/art ( 2059): Long monitor contention event with owner method=android.content.IContentProvider android.app.ActivityThread.acquireExistingProvider(android.content.Context, java.lang.String, int, boolean) from ActivityThread.java:4710 waiters=3 for 378ms E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.WindowManagerService.performLayoutAndPlaceSurfacesLockedInner:9751 com.android.server.wm.WindowManagerService.performLayoutAndPlaceSurfacesLockedLoop:8790 com.android.server.wm.WindowManagerService.performLayoutAndPlaceSurfacesLocked:8732 com.android.server.wm.WindowManagerService.executeAppTransition:4151 E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.WindowState.getDisplayContent:782 com.android.server.wm.WindowStateAnimator.updateSurfaceWindowCrop:1284 com.android.server.wm.WindowStateAnimator.setSurfaceBoundariesLocked:1459 com.android.server.wm.WindowManagerService.performLayoutAndPlaceSurfacesLockedInner:9839 E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.WindowState.getDisplayContent:782 com.android.server.wm.WindowState.isDefaultDisplay:1286 com.android.server.wm.WindowStateAnimator.updateSurfaceWindowCrop:1294 com.android.server.wm.WindowStateAnimator.setSurfaceBoundariesLocked:1459 E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.WindowState.getDisplayContent:782 com.android.server.wm.WindowState.getDisplayId:787 com.android.server.wm.InputMonitor.updateInputWindowsLw:294 com.android.server.wm.WindowManagerService.performLayoutAndPlaceSurfacesLockedInner:10090 E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.DisplayContent.setTouchExcludeRegion:237 com.android.server.wm.WindowManagerService.setFocusedStackFrame:3994 com.android.server.wm.WindowManagerService.performLayoutAndPlaceSurfacesLockedInner:10166 com.android.server.wm.WindowManagerService.performLayoutAndPlaceSurfacesLockedLoop:8790 E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.WindowState.getDisplayContent:782 com.android.server.wm.WindowStateAnimator.stepAnimationLocked:293 com.android.server.wm.WindowAnimator.updateWindowsLocked:289 com.android.server.wm.WindowAnimator.animateLocked:674 E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.WindowState.getDisplayContent:782 com.android.server.wm.WindowState.getDisplayId:787 com.android.server.wm.WindowStateAnimator.computeShownFrameLocked:1057 com.android.server.wm.WindowStateAnimator.prepareSurfaceLocked:1476 E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.WindowState.getDisplayContent:782 com.android.server.wm.WindowStateAnimator.updateSurfaceWindowCrop:1284 com.android.server.wm.WindowStateAnimator.setSurfaceBoundariesLocked:1459 com.android.server.wm.WindowStateAnimator.prepareSurfaceLocked:1478 E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.WindowState.getDisplayContent:782 com.android.server.wm.WindowState.isDefaultDisplay:1286 com.android.server.wm.WindowStateAnimator.updateSurfaceWindowCrop:1294 com.android.server.wm.WindowStateAnimator.setSurfaceBoundariesLocked:1459 E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.WindowState.getDisplayContent:782 com.android.server.wm.WindowState.getWindowList:1354 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2774 com.android.server.wm.WindowManagerService.removeWindowLocked:2675 E/WindowState( 1483): getStack: Window{e0208f3 u0 Starting com.example.helloworld} couldn't find taskId=4 Callers=com.android.server.wm.WindowState.getDisplayContent:782 com.android.server.wm.WindowManagerService.removeWindowInnerLocked:2779 com.android.server.wm.WindowManagerService.removeWindowLocked:2675 com.android.server.wm.WindowManagerService.removeWindow:2597 I/OpenGLRenderer( 1483): Initialized EGL, version 1.4 W/TpHceSessionManager( 1717): refreshCachedSession: init gcore [CONTEXT service_id=79 ] W/atgo ( 1717): createWithoutChecks: no account [CONTEXT service_id=79 ] W/TpHceSessionManager( 1717): refreshCachedSession succeeded [CONTEXT service_id=79 ] D/EGL_emulation( 1483): eglCreateContext: 0xaf23c940: maj 2 min 0 rcv 2 D/EGL_emulation( 1483): eglMakeCurrent: 0xaf23c940: ver 2 0 (tinfo 0xb42eaf20) D/EGL_emulation( 1483): eglMakeCurrent: 0xaf23c940: ver 2 0 (tinfo 0xb42eaf20) I/TelephonySpam( 2059): SpamListSync - scheduleOneTimeTask W/NetworkScheduler( 1717): Immediate task was not started com.google.android.gms/.tapandpay.gcmtask.TapAndPayGcmTaskService{u=0 tag="hce.dynamic_aid_registration.oneoff" trigger=window{start=0s,end=1s,earliest=0s,latest=0s} requirements=[NET_ANY] attributes=[PERSISTED] scheduled=0s last_run=N/A jid=N/A status=PENDING retries=0 client_lib=GMS_TASK_SCHEDULER-202414000}. Rescheduling immediate tasks can cause excessive battery drain. D/EGL_emulation( 2659): eglCreateContext: 0xaf0b4100: maj 2 min 0 rcv 2 D/EGL_emulation( 2659): eglMakeCurrent: 0xaf0b4100: ver 2 0 (tinfo 0xaf039780) I/ModuleSetMgr( 2059): Disabling container optional module downloads. I/PeopleInitIntentOp( 2059): Not initializing debuggability [CONTEXT service_id=5 ] I/ModuleSetMgr( 2059): Container features not yet downloaded are disabled. I/DynamiteModule( 2059): Considering local module com.google.android.gms.cronet_dynamite:0 and remote module com.google.android.gms.cronet_dynamite:1311 I/DynamiteModule( 2059): Selected remote version of com.google.android.gms.cronet_dynamite, version >= 1311 D/EGL_emulation( 1771): eglMakeCurrent: 0xaf1c40a0: ver 2 0 (tinfo 0xaf039470) I/art ( 1907): WaitForGcToComplete blocked for 16.772ms for cause HomogeneousSpaceCompact W/art ( 1717): Long monitor contention event with owner method=boolean android.app.ActivityThread.releaseProvider(android.content.IContentProvider, boolean) from ActivityThread.java:4744 waiters=1 for 101ms W/linker ( 2059): libcronet.83.0.4103.60.so: unused DT entry: type 0x6ffffef5 arg 0x2344 W/linker ( 2059): libcronet.83.0.4103.60.so: unused DT entry: type 0x6ffffffe arg 0x22e4 W/linker ( 2059): libcronet.83.0.4103.60.so: unused DT entry: type 0x6fffffff arg 0x3 I/Wear_Controller( 2059): Wear requires a companion app to be installed. I/Wear_Controller( 2059): onStartup - Wear is not available on this device. I/Vision ( 2059): Supported ABIS: [x86] I/art ( 1717): Background sticky concurrent mark sweep GC freed 20350(1209KB) AllocSpace objects, 6(105KB) LOS objects, 5% free, 16MB/17MB, paused 16.923ms total 244.711ms W/art ( 1717): Long monitor contention event with owner method=boolean android.app.ActivityThread.releaseProvider(android.content.IContentProvider, boolean) from ActivityThread.java:4744 waiters=2 for 219ms I/cr_CronetLibraryLoader( 2059): Cronet version: 83.0.4103.60, arch: i686 I/art ( 1150): Background concurrent mark sweep GC freed 792(33KB) AllocSpace objects, 0(0B) LOS objects, 91% free, 93KB/1117KB, paused 13.981ms total 67.742ms I/ActivityManager( 1483): Start proc 2757:com.google.android.gms.ui/u0a7 for service com.google.android.gms/.chimera.UiIntentOperationService E/libprocessgroup( 2757): failed to make and chown /acct/uid_10007: Read-only file system W/Zygote ( 2757): createProcessGroup failed, kernel missing CONFIG_CGROUP_CPUACCT? D/ConnectivityService( 1483): listenForNetwork for NetworkRequest [ id=3, legacyType=-1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED] ] D/ConnectivityService( 1483): handleRegisterNetworkRequest checking NetworkAgentInfo [MOBILE (LTE) - 100] D/ConnectivityService( 1483): apparently satisfied. currentScore=50 D/ConnectivityManager.CallbackHandler( 2059): CM callback handler got msg 524290 I/ModuleSetMgr( 2059): Container features not yet downloaded are disabled. D/EGL_emulation( 1771): eglMakeCurrent: 0xaf1c40a0: ver 2 0 (tinfo 0xaf039470) I/Choreographer( 1483): Skipped 47 frames! The application may be doing too much work on its main thread. I/ActivityManager( 1483): Waited long enough for: ServiceRecord{d10017c u0 com.android.music/.MediaPlaybackService} D/EGL_emulation( 1771): eglMakeCurrent: 0xaf1c40a0: ver 2 0 (tinfo 0xaf039470) D/EGL_emulation( 1771): eglMakeCurrent: 0xaf1c40a0: ver 2 0 (tinfo 0xaf039470) W/OpenGLRenderer( 1771): Incorrectly called buildLayer on View: aep, destroying layer... D/EGL_emulation( 1771): eglMakeCurrent: 0xaf1c40a0: ver 2 0 (tinfo 0xaf039470) W/ResourcesManager( 2757): Asset path '/system/framework/com.android.media.remotedisplay.jar' does not exist or contains no resources. W/ResourcesManager( 2757): Asset path '/system/framework/com.android.location.provider.jar' does not exist or contains no resources. I/Vision ( 2059): Requesting optional module download of ocr. I/ChimeraConfigurator( 2059): Update complete: success I/ModuleSetMgr( 2059): Container features not yet downloaded are disabled. I/GmsModuleFndr( 2059): Beginning GMS chimera module scan I/Vision ( 2059): Checking for download completion for 52850 -- ocr I/ModuleSetMgr( 2059): Computing pending module set with APKs: [[AdsFdrDynamite.integ:21460000000], [Cronetdynamite:202414020700], [Dynamiteloader:202414020700], [Dynamitemodulesa:202414020000], [Dynamitemodulesc:202414020000], [Googlecertificates:202414020000], [Mapsdynamite:202414020000], [Measurementdynamite:202414020000]] I/ModuleSetMgr( 2059): Pending container module APKs: [] I/ModuleSetMgr( 2059): Pending non-container module APKs: [] W/art ( 1717): Long monitor contention event with owner method=android.content.IContentProvider android.app.ActivityThread.acquireExistingProvider(android.content.Context, java.lang.String, int, boolean) from ActivityThread.java:4710 waiters=1 for 258ms I/art ( 2659): Background sticky concurrent mark sweep GC freed 8246(846KB) AllocSpace objects, 5(258KB) LOS objects, 10% free, 9MB/10MB, paused 1.925ms total 202.319ms W/art ( 1717): Long monitor contention event with owner method=android.content.IContentProvider android.app.ActivityThread.acquireExistingProvider(android.content.Context, java.lang.String, int, boolean) from ActivityThread.java:4710 waiters=2 for 256ms W/art ( 1717): Long monitor contention event with owner method=android.content.IContentProvider android.app.ActivityThread.acquireExistingProvider(android.content.Context, java.lang.String, int, boolean) from ActivityThread.java:4710 waiters=3 for 171ms I/art ( 1483): Explicit concurrent mark sweep GC freed 19702(1106KB) AllocSpace objects, 14(1377KB) LOS objects, 31% free, 8MB/12MB, paused 2.375ms total 88.374ms I/art ( 1717): Background partial concurrent mark sweep GC freed 41813(2MB) AllocSpace objects, 3(100KB) LOS objects, 20% free, 15MB/19MB, paused 1.939ms total 315.874ms I/art ( 1938): Explicit concurrent mark sweep GC freed 3979(176KB) AllocSpace objects, 0(0B) LOS objects, 45% free, 1214KB/2MB, paused 1.714ms total 57.419ms I/ChimeraConfigurator( 2059): Starting update, reason: 4 urgentFeatures: vision.ocr:-1 E/ConfigFileUtils( 2059): Failed to read config file: /data/data/com.google.android.gms/app_chimera/next_container.pb: open failed: ENOENT (No such file or directory) I/ModuleSetMgr( 2059): Disabling container optional module downloads. I/ModuleSetMgr( 2059): Container features not yet downloaded are disabled. I/ModuleSetMgr( 2059): Container features not yet downloaded are disabled. I/ChimeraConfigurator( 2059): Update complete: success I/ModuleSetMgr( 2059): Container features not yet downloaded are disabled. I/GmsModuleFndr( 2059): Beginning GMS chimera module scan I/ModuleSetMgr( 2059): Computing pending module set with APKs: [[AdsFdrDynamite.integ:21460000000], [Cronetdynamite:202414020700], [Dynamiteloader:202414020700], [Dynamitemodulesa:202414020000], [Dynamitemodulesc:202414020000], [Googlecertificates:202414020000], [Mapsdynamite:202414020000], [Measurementdynamite:202414020000]] I/ModuleSetMgr( 2059): Pending container module APKs: [] I/ModuleSetMgr( 2059): Pending non-container module APKs: [] I/Checkin ( 2059): [EventLogChimeraService] Opted in for usage reporting: false I/Checkin ( 2059): [EventLogChimeraService] Aggregate from 1607028658834 (log), 0 (data) W/art ( 1717): Long monitor contention event with owner method=int apzc.a(apyw, aage) from :com.google.android.gms@202414013@20.24.14 (020700-319035315):88 waiters=0 for 1.489s V/NFC ( 1717): this device does not have NFC support W/atdh ( 1717): NfcAdapter is null while obtaining CardEmulation instance. [CONTEXT service_id=79 ] W/atdh ( 1717): CardEmulation is null while checking aid registration. [CONTEXT service_id=79 ] I/atdh ( 1717): Running in an emulator. Exiting without registering AIDs [CONTEXT service_id=79 ] I/TapAndPayAidRegistratio( 1717): Registration matches requested list. Skipping AID registration. [CONTEXT service_id=79 ] W/DynamiteModule( 2757): Local module descriptor class for providerinstaller not found. I/art ( 2059): Background sticky concurrent mark sweep GC freed 38891(2MB) AllocSpace objects, 32(512KB) LOS objects, 10% free, 17MB/19MB, paused 9.280ms total 116.046ms W/ProviderHelper( 2757): Unknown dynamite feature providerinstaller I/DynamiteModule( 2757): Considering local module providerinstaller:0 and remote module providerinstaller:0 W/ProviderInstaller( 2757): Failed to load providerinstaller module: No acceptable module found. Local version is 0 and remote version is 0. I/GCoreUlr( 1717): WorldUpdater received intent Intent { act=com.google.android.location.reporting.ACTION_UPDATE_WORLD cmp=com.google.android.gms/com.google.android.location.reporting.service.DispatchingService (has extras) } with receiverAction init W/linker ( 2757): libconscrypt_gmscore_jni.so: unused DT entry: type 0x6ffffef5 arg 0x868 W/linker ( 2757): libconscrypt_gmscore_jni.so: unused DT entry: type 0x6ffffffe arg 0x828 W/linker ( 2757): libconscrypt_gmscore_jni.so: unused DT entry: type 0x6fffffff arg 0x2 V/NativeCrypto( 2757): Registering com/google/android/gms/org/conscrypt/NativeCrypto's 286 native methods... W/art ( 2757): Suspending all threads took: 17.554ms I/ProviderInstaller( 2757): Installed default security provider GmsCore_OpenSSL I/Safeboot( 2757): Checking safeboot... I/Safeboot( 2757): Not entering safeboot; wrong process. I/GCoreUlr( 1717): WorldUpdater:init: Ensuring that reporting is stopped because of reasons: (no Google accounts) I/GCoreUlr( 1717): Unbound from all signal providers. I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.checkin.EventLogService started execution. cause:7 exec_start_elapsed_seconds: 43 [CONTEXT service_id=218 ] I/GCoreUlr( 1717): Unbound from all signal providers. I/GCoreUlr( 1717): Stopping handler for UlrDispSvcFast I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.tapandpay.gcmtask.TapAndPayGcmTaskService started execution. cause:7 exec_start_elapsed_seconds: 43 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.tapandpay.gcmtask.TapAndPayGcmTaskService finished executing. cause:7 result: 1 elapsed_millis: 83 uptime_millis: 83 exec_start_elapsed_seconds: 43 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.checkin.EventLogService finished executing. cause:7 result: 1 elapsed_millis: 717 uptime_millis: 717 exec_start_elapsed_seconds: 43 [CONTEXT service_id=218 ] W/art ( 2757): Suspending all threads took: 5.165ms I/bebp ( 2757): Primes not initialized, returning default (no-op) Primes instance which will ignore all calls. Please call Primes.initialize(...) before using any Primes API. I/ChimeraConfigurator( 2059): Starting update, reason: 3 urgentFeatures: E/ConfigFileUtils( 2059): Failed to read config file: /data/data/com.google.android.gms/app_chimera/next_container.pb: open failed: ENOENT (No such file or directory) I/ModuleSetMgr( 2059): Disabling container optional module downloads. I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.chimera.container.ConfigService started execution. cause:5 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.auth.account.be.legacy.AuthCronService started execution. cause:5 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] W/ProviderHelper( 2059): Unknown dynamite feature providerinstaller I/DynamiteModule( 2059): Considering local module providerinstaller:0 and remote module providerinstaller:0 I/DynamiteModule( 2059): Considering local module providerinstaller:0 and remote module providerinstaller:0 W/Conscrypt( 2059): Could not set socket write timeout: java.net.SocketException: Socket closed E/art ( 2059): invalid stream - problem with parameter iterator in /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk:classes3.dex for method void com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(int) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.Platform.setSocketWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315):2) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315)) I/ModuleSetMgr( 2059): Container features not yet downloaded are disabled. I/ModuleSetMgr( 2059): Container features not yet downloaded are disabled. I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.auth.account.be.legacy.AuthCronService finished executing. cause:5 result: 3 elapsed_millis: 192 uptime_millis: 192 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] I/ChmraDebugLogger( 2059): [73] 1801 I/ChmraDebugLogger( 2059): [30] [AdsFdrDynamite.integ:203404601000000] permitMetered=true, W/Conscrypt( 2059): Could not set socket write timeout: java.net.SocketException: Socket closed E/art ( 2059): invalid stream - problem with parameter iterator in /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk:classes3.dex for method void com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(int) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.Platform.setSocketWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315):2) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315)) W/ChimeraConfigService( 2059): Retry attempt was throttled. I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.chimera.container.ConfigService finished executing. cause:5 result: 2 elapsed_millis: 310 uptime_millis: 310 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.gass.chimera.SchedulePeriodicTasksService started execution. cause:5 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.gass.chimera.SchedulePeriodicTasksService started execution. cause:5 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.gass.chimera.SchedulePeriodicTasksService finished executing. cause:5 result: 1 elapsed_millis: 160 uptime_millis: 160 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.ads.social.GcmSchedulerWakeupService started execution. cause:5 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.ads.social.GcmSchedulerWakeupService finished executing. cause:5 result: 1 elapsed_millis: 86 uptime_millis: 86 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] D/TaskPersister( 1483): removeObsoleteFile: deleting file=4_task.xml I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.clearcut.uploader.QosUploaderService started execution. cause:5 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.clearcut.uploader.QosUploaderService finished executing. cause:5 result: 1 elapsed_millis: 157 uptime_millis: 156 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] I/Checkin ( 2059): [CheckinRequestProcessor] updateCheckinIdTokenFileFromResponse, Reading existing AID I/Checkin ( 2059): [CheckinRequestProcessor] Default Task : Checkin Succeeded: https://android.googleapis.com/checkin (fragment #1). D/ ( 2659): HostConnection::get() New Host Connection established 0x9acd9150, tid 2675 D/EGL_emulation( 2659): eglCreateContext: 0xb40f28e0: maj 2 min 0 rcv 2 D/EGL_emulation( 2659): eglMakeCurrent: 0xb40f28e0: ver 2 0 (tinfo 0x9aca86b0) I/Checkin ( 2059): [CheckinOperation] Checkin Operation finished with result: 0 finish time: 45195. I/art ( 2059): Background sticky concurrent mark sweep GC freed 19985(1248KB) AllocSpace objects, 20(332KB) LOS objects, 8% free, 18MB/19MB, paused 11.791ms total 53.704ms I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.tapandpay.gcmtask.TapAndPayGcmTaskService started execution. cause:5 exec_start_elapsed_seconds: 45 [CONTEXT service_id=218 ] I/art ( 1938): Explicit concurrent mark sweep GC freed 4450(218KB) AllocSpace objects, 0(0B) LOS objects, 45% free, 1220KB/2MB, paused 1.495ms total 17.356ms W/AudioTrack( 1483): AUDIO_OUTPUT_FLAG_FAST denied by client D/EGL_emulation( 1483): eglMakeCurrent: 0xaf23c940: ver 2 0 (tinfo 0xb42eaf20) W/InputMethodManagerService( 1483): Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@a197cfa attribute=null, token = android.os.BinderProxy@2146fe3f D/EGL_emulation( 2659): eglCreateContext: 0xb438dd60: maj 2 min 0 rcv 2 D/EGL_emulation( 2659): eglMakeCurrent: 0xb438dd60: ver 2 0 (tinfo 0x9aca86b0) I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.tapandpay.gcmtask.TapAndPayGcmTaskService finished executing. cause:5 result: 1 elapsed_millis: 149 uptime_millis: 149 exec_start_elapsed_seconds: 45 [CONTEXT service_id=218 ] E/cutils-trace( 1146): Error opening trace file: Permission denied (13) I/HotwordRecognitionRnr( 1907): Starting hotword detection. I/MicrophoneInputStream( 1907): mic_starting gzi@2db1ed8e I/Icing ( 2059): IndexChimeraService.getServiceInterface callingPackage=com.google.android.googlequicksearchbox componentName=null serviceId=21 E/audio_hw_generic( 1146): Error opening input stream format 1, channel_mask 0010, sample_rate 16000 I/AudioFlinger( 1146): AudioFlinger's thread 0xb550e000 ready to run I/MicrophoneInputStream( 1907): mic_started gzi@2db1ed8e W/Icing ( 2059): No valid account for uploading user actions I/HotwordWorker( 1907): onReady I/Icing ( 2059): Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0 I/Icing ( 2059): IndexChimeraService.getServiceInterface callingPackage=com.google.android.gms componentName=null serviceId=30 I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.tapandpay.gcmtask.TapAndPayGcmTaskService started execution. cause:5 exec_start_elapsed_seconds: 45 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.tapandpay.gcmtask.TapAndPayGcmTaskService finished executing. cause:5 result: 1 elapsed_millis: 67 uptime_millis: 67 exec_start_elapsed_seconds: 45 [CONTEXT service_id=218 ] W/Icing ( 2059): No valid account for uploading user actions I/Icing ( 2059): Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0 I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.tapandpay.gcmtask.TapAndPayGcmTaskService started execution. cause:5 exec_start_elapsed_seconds: 45 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.tapandpay.gcmtask.TapAndPayGcmTaskService finished executing. cause:5 result: 1 elapsed_millis: 115 uptime_millis: 114 exec_start_elapsed_seconds: 45 [CONTEXT service_id=218 ] I/art ( 1771): Background sticky concurrent mark sweep GC freed 7480(396KB) AllocSpace objects, 0(0B) LOS objects, 10% free, 4MB/5MB, paused 15.504ms total 45.981ms W/Conscrypt( 2059): Could not set socket write timeout: java.net.SocketException: Socket closed E/art ( 2059): invalid stream - problem with parameter iterator in /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk:classes3.dex for method void com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(int) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.Platform.setSocketWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315):2) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315)) I/art ( 1483): Explicit concurrent mark sweep GC freed 21090(1068KB) AllocSpace objects, 6(535KB) LOS objects, 32% free, 8MB/12MB, paused 1.673ms total 42.430ms W/Conscrypt( 2059): Could not set socket write timeout: java.net.SocketException: Socket closed E/art ( 2059): invalid stream - problem with parameter iterator in /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk:classes3.dex for method void com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(int) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.Platform.setSocketWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315):2) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315)) I/ActivityManager( 1483): Waited long enough for: ServiceRecord{a4c7b64 u0 com.google.android.calendar/com.android.calendar.alerts.InitAlarmsService} I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.tapandpay.gcmtask.TapAndPayGcmTaskService started execution. cause:5 exec_start_elapsed_seconds: 46 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.tapandpay.gcmtask.TapAndPayGcmTaskService finished executing. cause:5 result: 1 elapsed_millis: 142 uptime_millis: 142 exec_start_elapsed_seconds: 46 [CONTEXT service_id=218 ] W/Conscrypt( 2059): Could not set socket write timeout: java.net.SocketException: Socket closed E/art ( 2059): invalid stream - problem with parameter iterator in /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk:classes3.dex for method void com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(int) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.Platform.setSocketWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315):2) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315)) W/Conscrypt( 2059): Could not set socket write timeout: java.net.SocketException: Socket closed E/art ( 2059): invalid stream - problem with parameter iterator in /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk:classes3.dex for method void com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(int) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.Platform.setSocketWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315):2) W/Conscrypt( 2059): at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(:com.google.android.gms@202414013@20.24.14 (020700-319035315)) I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.udc.service.UdcContextInitService started execution. cause:5 exec_start_elapsed_seconds: 46 [CONTEXT service_id=218 ] E/Vold ( 1135): Failed to find mounted volume for /storage/sdcard/Android/data/com.google.android.gms/files/ W/Vold ( 1135): Returning OperationFailed - no handler for errno 0 W/ContextImpl( 2059): Failed to ensure directory: /storage/sdcard/Android/data/com.google.android.gms/files I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.udc.service.UdcContextInitService finished executing. cause:5 result: 1 elapsed_millis: 260 uptime_millis: 260 exec_start_elapsed_seconds: 46 [CONTEXT service_id=218 ] I/NetworkScheduler.Stats( 1717): Task com.google.android.gms/com.google.android.gms.gass.chimera.SchedulePeriodicTasksService finished executing. cause:5 result: 1 elapsed_millis: 2252 uptime_millis: 2252 exec_start_elapsed_seconds: 44 [CONTEXT service_id=218 ] W/AudioFlinger( 1146): write blocked for 1561 msecs, 1 delayed writes, thread 0xb58aa000 W/Nearby ( 1717): Failed attempt #3 out of 3 for RestoreDeviceName [CONTEXT service_id=49 ] W/Nearby ( 1717): java.lang.IllegalStateException: BluetoothAdapter not ready yet! W/Nearby ( 1717): at aiyj.d(:com.google.android.gms@202414013@20.24.14 (020700-319035315):2) W/Nearby ( 1717): at aiyi.run(Unknown Source) W/Nearby ( 1717): at bvhi.call(Unknown Source) W/Nearby ( 1717): at bvhn.a(:com.google.android.gms@202414013@20.24.14 (020700-319035315):2) W/Nearby ( 1717): at bvhn.a(:com.google.android.gms@202414013@20.24.14 (020700-319035315):9) W/Nearby ( 1717): at aiyh.run(Unknown Source) W/Nearby ( 1717): at java.lang.Thread.run(Thread.java:818) W/Nearby ( 1717): at sbn.run(Unknown Source) W/Nearby ( 1717): at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1112) W/Nearby ( 1717): at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:587) W/Nearby ( 1717): at shh.run(:com.google.android.gms@202414013@20.24.14 (020700-319035315):0) W/Nearby ( 1717): at java.lang.Thread.run(Thread.java:818) I/ActivityManager( 1483): Killing 1804:com.android.printspooler/u0a44 (adj 15): empty #17 W/libprocessgroup( 1483): failed to open /acct/uid_10044/pid_1804/cgroup.procs: No such file or directory I/Vision ( 2059): Requesting optional module download of ocr. I/Vision ( 2059): Checking for download completion for 62867 -- ocr I/InputReader( 1483): Reconfiguring input devices. changes=0x00000010 I/GmscoreIpa( 2059): Using regular flags by method 2 [CONTEXT service_id=255 ] D/BackupManagerService( 1483): Received broadcast Intent { act=android.intent.action.PACKAGE_CHANGED dat=package:com.google.android.gms flg=0x4000010 (has extras) } I/BackupManagerService( 1483): Unbinding ComponentInfo{com.google.android.gms/com.google.android.gms.backup.BackupTransportService} I/Fitness ( 1717): FitCleanupIntentOperation received Intent android.intent.action.PACKAGE_CHANGED [CONTEXT service_id=17 ] I/Fitness ( 1717): OnPackageChangedOperation got intent: Intent { act=android.intent.action.PACKAGE_CHANGED dat=package:com.google.android.gms flg=0x4000010 cmp=com.google.android.gms/.chimera.PersistentIntentOperationService (has extras) } [CONTEXT service_id=17 ] I/UpdateIcingCorporaServi( 1907): Updating corpora: APPS=com.google.android.gms, CONTACTS=MAYBE W/Launcher( 1771): setApplicationContext called twice! old=com.google.android.velvet.VelvetApplication@2d0dd57 new=com.google.android.velvet.VelvetApplication@2d0dd57 I/Icing ( 2059): IndexChimeraService.getServiceInterface callingPackage=com.google.android.gms componentName=AppsCorpus serviceId=36 I/BackupManagerService( 1483): Binding to transport host ComponentInfo{com.google.android.gms/com.google.android.gms.backup.BackupTransportService} I/Icing ( 2059): IndexChimeraService.getServiceInterface callingPackage=com.google.android.googlequicksearchbox componentName=null serviceId=21 I/Icing ( 2059): updateResources: need to parse abpq{com.google.android.gms} E/art ( 1717): invalid stream - problem with parameter iterator in /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk:classes2.dex for method void low.(android.content.Context) W/art ( 1771): Suspending all threads took: 11.028ms E/AppsCustomizePagedView( 1771): Widget ComponentInfo{com.google.android.googlequicksearchbox/com.google.android.apps.sidekick.widget.PredictiveCardsWidgetProvider} can not fit on this device (300, 250) I/art ( 1771): Background sticky concurrent mark sweep GC freed 5426(217KB) AllocSpace objects, 0(0B) LOS objects, 6% free, 4MB/5MB, paused 12.796ms total 42.299ms E/art ( 1717): invalid stream - problem with parameter iterator in /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk:classes2.dex for method void low.(android.content.Context) V/BackupManagerService( 1483): Connected to transport ComponentInfo{com.google.android.gms/com.google.android.gms.backup.BackupTransportService} V/BackupManagerService( 1483): Registering transport com.google.android.gms/.backup.BackupTransportService::com.google.android.gms/.backup.BackupTransportService = com.android.internal.backup.IBackupTransport$Stub$Proxy@ec990be D/Backup ( 1717): [BackupScheduler] Setting backup scheduler enabled=true due to desired state 1 V/Backup ( 1717): [BackupTransportCS] no backup now D/Backup ( 1717): [BackupScheduler] Setting backup scheduler enabled=true due to desired state 1 V/Backup ( 1717): [BackupTransportCS] no backup now V/Backup ( 1717): [BackupTransportCS] Accounts changed I/Backup ( 1717): [GmsBackupAccountManager] Backup account not found in gmscore. V/Backup ( 1717): [BackupAccountNotifier] Handling new intent. Show notification: true V/Backup ( 1717): [BackupAccountNotifier] Notification is allowed: true V/Backup ( 1717): [BackupAccountNotifier] Shared pref value: true I/Backup ( 1717): [BackupAccountNotifier] Showing notification. I/art ( 1717): Background partial concurrent mark sweep GC freed 45801(2MB) AllocSpace objects, 2(39KB) LOS objects, 19% free, 16MB/20MB, paused 5.779ms total 104.748ms W/Icing ( 2059): No valid account for uploading user actions I/Icing ( 2059): Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0 W/Icing ( 2059): No valid account for uploading user actions I/Icing ( 2059): Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0 I/Icing ( 2059): Indexing com.google.android.gms-internal.3p:MobileApplication from com.google.android.gms using seqno 64 I/Icing ( 2059): Indexing com.google.android.gms-internal.3p:MobileApplication from com.google.android.gms using seqno 64 I/Icing ( 2059): Indexing com.google.android.gms-internal.3p:MobileApplication from com.google.android.gms using seqno 64 I/Icing ( 2059): Indexing com.google.android.gms-apps from com.google.android.gms I/Icing ( 2059): Indexing done com.google.android.gms-apps I/ApplicationLogger( 1907): canRun() : Opted Out I/UpdateIcingCorporaServi( 1907): UpdateCorporaTask done [took 695 ms] updated apps [took 695 ms] I/ActivityManager( 1483): Killing 2330:com.android.dialer/u0a4 (adj 15): empty #17 W/libprocessgroup( 1483): failed to open /acct/uid_10004/pid_2330/cgroup.procs: No such file or directory I/Vision ( 2059): Requesting optional module download of ocr. I/Vision ( 2059): Checking for download completion for 72882 -- ocr I/Vision ( 2059): Requesting optional module download of ocr. I/Vision ( 2059): Checking for download completion for 82907 -- ocr I/Vision ( 2059): Requesting optional module download of ocr. I/Vision ( 2059): Checking for download completion for 92932 -- ocr ```
freakboy3742 commented 3 years ago

Thanks for the report. I can't think of any reason why 5.1.1 would be a problem (especially since it's apparently only an issue on physical device). I don't suppose you can provide a stack trace of the failure?

As for target SDK compatibility - it's possible we might have those misconfigured; I know we are dependent on a couple of recent API features, and while we were developing the backend we modified our minimum supported version a couple of times. @paulproteus - I don't suppose you remember off the top of your head where we ended up on those discussions?

paulproteus commented 3 years ago

I honestly don't recall where we landed on API 21 vs. 22. It could honestly be the case that something dynamic requires API 22, or even 23. I dimly recall in June/July/August discovering something that needed 22 or even 23, and we ran with it.

The issue here seems to be a Java ClassNotFound exception. I'd personally love to know the name of the class it's failing to instantiate.

I filed https://github.com/beeware/rubicon-java/issues/54 to improve exception handling in rubicon-java.

To find out what's going on ASAP, this patch to rubicon-java will print every Java class name that gets accessed by Python. Perhaps a next step is to rebuild rubicon-java with that patch and give you instructions @czoins to run your app with this, and then see what the log says?

diff --git a/jni/rubicon.c b/jni/rubicon.c
index 825b141..c21dff3 100644
--- a/jni/rubicon.c
+++ b/jni/rubicon.c
@@ -145,6 +145,7 @@ jclass DefineClass(const char *name, jobject loader, const jbyte *buf, jsize len
     return (*java)->DefineClass(java, name, loader, buf, len);
 }
 jclass FindClass(const char *name) {
+    LOG_E("Trying to call FindClass on %s", name);
     return (*java)->FindClass(java, name);
 }
 jmethodID FromReflectedMethod(jobject method) {
czoins commented 3 years ago

@freakboy3742 I think i wasn't very clear. It's not crashing on a physical device only, but both the 22 level API device and emulator. I haven't tested it on 21. And it works on 23.

@paulproteus What should i do exactly to test my app with this patch?

freakboy3742 commented 2 years ago

I'm going to make a judgement call and close this issue.

While it would be desirable to support older Android versions, it's unlikely this is ever going to be prioritised as something to fix; and with the progress of time, it becomes increasingly unlikely that anyone will be motivated to look into the problem.

If someone wants to present a patch for this, I'll be happy to consider it for merging (assuming it doesn't require any changes that would pose complications for the support of more recent Android versions).