Detox 20 fails to run android tests

AshrafRizvi01 commented 1 year ago

What happened?

While running detox on android it failed to work after building the test apk again. The same config worked before using the old test binary. So seems like the update to 20 is the culprit.

What I have tried:

Updating to latest 20.7.0 and rebuilding test binary and re-running tests. Cleaning up the emulator and even creating a fresh one to check.

Even after that the same issue occurs.

10:54:52.053 detox[17454] i child-process:EXEC_FAIL ""/Users/xxx/Library/Android/sdk/platform-tools/adb" -s emulator-13740 shell "ps | grep \"com\.xxx\.package$\""" failed with error = ChildProcessError: Command failed: "/Users/xxx/Library/Android/sdk/platform-tools/adb" -s emulator-13740 shell "ps | grep \"com\.xxx\.package$\""
 `"/Users/xxx/Library/Android/sdk/platform-tools/adb" -s emulator-13740 shell "ps | grep \"com\.xxx\.package$\""` (exited with error code 1) (code=1), stdout and stderr:

What was the expected behaviour?

Should run the tests in the emulator.

Was it tested on latest Detox?

[X] I have tested this issue on the latest Detox release and it still reproduces.

Did your test throw out a timeout?

[X] I have followed the instructions under Identifying which synchronization mechanism causes us to wait too much.

Help us reproduce this issue!

The issue did not occur on detox 19 and only started happening on detox 20.

In what environment did this happen?

Detox version: 20.7.0 React Native version: 0.67 Has Fabric (React Native's new rendering system) enabled: (yes/no) no Node version: 16.14.0 Device model: Pixel 3A (API 31) Emulator Android version: API 31 Test-runner (select one): Jest

Detox logs

``` 10:54:50.197 detox[17454] i child-process:EXEC_FAIL 10:54:50.197 detox[17454] i child-process:EXEC_FAIL 10:54:50.698 detox[17454] i child-process:EXEC_CMD "/Users/XXX/Library/Android/sdk/platform-tools/adb" -s emulator-13740 shell "ps | grep \"com\.xxx\.pkg$\"" 10:54:52.053 detox[17454] i child-process:EXEC_FAIL ""/Users/XXX/Library/Android/sdk/platform-tools/adb" -s emulator-13740 shell "ps | grep \"com\.xxx\.pkg$\""" failed with error = ChildProcessError: Command failed: "/Users/XXX/Library/Android/sdk/platform-tools/adb" -s emulator-13740 shell "ps | grep \"com\.xxx\.pkg$\"" `"/Users/XXX/Library/Android/sdk/platform-tools/adb" -s emulator-13740 shell "ps | grep \"com\.xxx\.pkg$\""` (exited with error code 1) (code=1), stdout and stderr: ```

Device logs

``` paste your device.log here! ```

More data, please!

No response

AshrafRizvi01 commented 1 year ago

In the device logs I am getting VERBOSE | Data frame has invalid ether type 0

noomorph commented 1 year ago

@d4vidi any clues?

d4vidi commented 1 year ago

I have an hypothesis. Could you run, right after detox fails, the same exact command as detox does (adb shell... ps | grep, etc.) and even things like adb shell lsand see if it fails? If it does, terminate the emulator and start a new one manually (from command line or even android studio), then run those commands again. Please share the results, including error messages.

AshrafRizvi01 commented 1 year ago

Hi @d4vidi I have ran the commands and here are the results: `$adb shell ls

acct apex bin bugreports cache config d data data_mirror debug_ramdisk dev etc init init.environ.rc linkerconfig lost+found metadata mnt odm odm_dlkm oem postinstall proc product sdcard second_stage_resources storage sys system system_ext vendor vendor_dlkm`

And I got no errors for adb shell ps | grep <packagename> and also no output.

AshrafRizvi01 commented 1 year ago

@d4vidi I ran the app manually and tried again and the ps | grep command gave me the process id. My understanding is that detox is not able to launch the app and hence when querying the process_id it fails because the output is empty.

Here is my launch app code:

const beforeAllHook = async () => {
  // eslint-disable-next-line no-console
  console.log('[beforeAllHook]: Initialised. Launching app...');
  await device.launchApp(DETOX_LAUNCH_CONFIG);
  await device.disableSynchronization();
  // eslint-disable-next-line no-console
  console.log('[beforeAllHook]: Launched app');
};

And here is my config:

export const DETOX_LAUNCH_CONFIG: Detox.DeviceLaunchAppConfig = {
  newInstance: true,
  launchArgs: { detoxEnableSynchronization: 0 },
  permissions: { notifications: 'YES', userTracking: 'YES' },
};

Logs:

13:45:38.644 detox[87649] i lifecycle world-in-brief.test.ts is assigned to emulator-13188 (pixel_3a)
13:45:38.646 detox[87649] i user [beforeAllHook]: Initialised. Launching app...
  origin: at init.ts:7:13
13:45:38.651 detox[87649] i child-process:EXEC_CMD "/Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb" -s emulator-13188 shell "am force-stop com.test.app"
13:45:38.711 detox[87649] i child-process:EXEC_CMD "/Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb" -s emulator-13188 shell "date +\"%m-%d %T.000\""
13:45:38.757 detox[87649] i child-process:EXEC_CMD "/Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb" -s emulator-13188 reverse tcp:58730 tcp:58730
13:45:38.785 detox[87649] i child-process:EXEC_CMD "/Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb" -s emulator-13188 shell "pm list instrumentation"
13:45:38.836 detox[87649] i child-process:SPAWN_CMD /Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb -s emulator-13188 shell am instrument -w -r -e detoxServer ws://localhost:58730 -e detoxSessionId 17637b71-e5d2-0110-f887-3a0bc2e5f509 -e detoxEnableSynchronization 0 -e debug false com.test.app.test/androidx.test.runner.AndroidJUnitRunner
13:45:39.131 detox[87649] i child-process:SPAWN_END /Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb -s emulator-13188 shell am instrument -w -r -e detoxServer ws://localhost:58730 -e detoxSessionId 17637b71-e5d2-0110-f887-3a0bc2e5f509 -e detoxEnableSynchronization 0 -e debug false com.test.app.test/androidx.test.runner.AndroidJUnitRunner exited with code #0
13:45:39.132 detox[87649] i child-process:EXEC_CMD "/Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb" -s emulator-13188 reverse --remove tcp:58730
13:45:39.338 detox[87649] i child-process:EXEC_CMD "/Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb" -s emulator-13188 shell "ps | grep \"com\.economist\.darwin$\""
13:45:40.503 detox[87649] i child-process:EXEC_FAIL ""/Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb" -s emulator-13188 shell "ps | grep \"com\.economist\.darwin$\""" failed with error = ChildProcessError: Command failed: "/Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb" -s emulator-13188 shell "ps | grep \"com\.economist\.darwin$\""
 `"/Users/ashrafiftekhar/Library/Android/sdk/platform-tools/adb" -s emulator-13188 shell "ps | grep \"com\.economist\.darwin$\""` (exited with error code 1) (code=1), stdout and stderr:

13:45:40.503 detox[87649] i child-process:EXEC_FAIL

d4vidi commented 1 year ago

So that sort of disproves my hypothesis. Could you share the device logs (i.e. Using adb logcat)

AshrafRizvi01 commented 1 year ago

Hi @d4vidi , Please find the device logs as follows:

Device logs

``` 05-03 14:34:33.887 521 521 I Telecom : InCallController: updateCarModeForConnections: car mode apps: : SSH.oR@AS8 05-03 14:34:33.891 12128 12128 D NetworkSecurityConfig: No Network Security Config specified, using platform default 05-03 14:34:33.891 12128 12128 D NetworkSecurityConfig: No Network Security Config specified, using platform default 05-03 14:34:33.897 521 521 I ConditionProviders: Disallowing condition provider com.economist.darwin.test (userSet: true) 05-03 14:34:33.900 7382 12030 I Finsky : [196] nix.run(2): Frosting DB delete succeeded: true 05-03 14:34:33.901 7382 12030 I Finsky : [196] nix.run(2): Frosting DB delete succeeded: false 05-03 14:34:33.901 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:33.904 12128 12128 I Finsky:background: [2] jkn.i(6): Process created at version: 35.2.19-21 [0] [PR] 522404461 05-03 14:34:33.905 7382 7460 I Finsky : [62] jfj.a(11): AIM: AppInfoManager-Perf > ItemModel > CacheSize=0, cacheHitCount=0, cacheMissCount=1, total appsWithNoServerDataCount=0. Missed in cache (limit 10) : [com.economist.darwin.test] 05-03 14:34:33.905 7382 7460 I Finsky : [62] jfm.n(3): AIM: AppInfoManager-Perf > ItemModel > ItemStore Called 1 apps cacheOptions = USE_CACHE 05-03 14:34:33.905 7382 7460 I Finsky : [62] jfj.a(96): AIM: ItemModel fetch - unauth(1), auth() 05-03 14:34:33.906 7382 7460 I Finsky : [62] rjm.apply(5): ItemStore: Not cached: com.economist.darwin.test 05-03 14:34:33.909 7094 9357 I FontLog : Fetch {Google Sans, wdth 100.0, wght 700, ital 0.0, bestEffort false} end status Status{statusCode=SUCCESS, resolution=null} [CONTEXT service_id=132 ] 05-03 14:34:33.922 7094 7112 I FontLog : Pulling font file for id = 10, cache size = 7 [CONTEXT service_id=132 ] 05-03 14:34:33.928 12064 12064 I csdo : Primes not initialized, returning default (no-op) Primes instance which will ignore all calls. Please call Primes.initialize(...) before using any Primes API. 05-03 14:34:33.934 521 521 W WifiService: Couldn't get PackageInfo for package:com.economist.darwin.test 05-03 14:34:33.934 521 521 D WifiService: Remove settings for package:com.economist.darwin.test 05-03 14:34:33.936 521 710 D WifiConfigManager: Remove all networks for app ApplicationInfo{c7be4c6 com.economist.darwin.test} 05-03 14:34:33.938 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.940 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.941 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.941 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.943 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.943 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.943 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.944 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.944 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.945 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.946 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.946 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.947 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.947 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.949 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.949 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.950 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.951 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.952 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.952 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.954 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.954 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.954 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.954 382 499 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.954 382 499 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.954 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.954 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.955 382 382 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted 05-03 14:34:33.955 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.956 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.956 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.956 521 710 D WifiConfigStore: Writing to stores completed in 19 ms. 05-03 14:34:33.956 521 710 I PasspointManager: No app ops listener found for com.economist.darwin.test 05-03 14:34:33.957 382 498 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted 05-03 14:34:33.965 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:33.966 12064 12157 I Watchcat: Started 05-03 14:34:33.976 7094 12070 I Fitness : FitCleanupIntentOperation received Intent android.intent.action.PACKAGE_REMOVED [CONTEXT service_id=17 ] 05-03 14:34:33.985 7094 10370 W FontLog : Error updating last accessed time for {Google Sans, wdth 100.0, wght 700, ital 0.0, bestEffort false}. [CONTEXT service_id=132 ] 05-03 14:34:33.985 7094 10370 W FontLog : java.lang.IllegalStateException: attempt to re-open an already-closed object: SQLiteDatabase: /data/user/0/com.google.android.gms/databases/metadata.db 05-03 14:34:33.985 7094 10370 W FontLog : at android.database.sqlite.SQLiteClosable.acquireReference(SQLiteClosable.java:58) 05-03 14:34:33.985 7094 10370 W FontLog : at android.database.sqlite.SQLiteDatabase.executeSql(SQLiteDatabase.java:1903) 05-03 14:34:33.985 7094 10370 W FontLog : at android.database.sqlite.SQLiteDatabase.execSQL(SQLiteDatabase.java:1898) 05-03 14:34:33.985 7094 10370 W FontLog : at anfv.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):5) 05-03 14:34:33.985 7094 10370 W FontLog : at abqw.c(:com.google.android.gms@231516047@23.15.16 (190800-527344967):6) 05-03 14:34:33.985 7094 10370 W FontLog : at abqw.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):7) 05-03 14:34:33.985 7094 10370 W FontLog : at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) 05-03 14:34:33.985 7094 10370 W FontLog : at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) 05-03 14:34:33.985 7094 10370 W FontLog : at abwc.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):0) 05-03 14:34:33.985 7094 10370 W FontLog : at java.lang.Thread.run(Thread.java:920) 05-03 14:34:33.991 521 565 I CompanionDeviceManagerService: Writing associations to disk: [] 05-03 14:34:33.993 12064 12157 I GMS_MM_Logger: Start time: 135044 05-03 14:34:33.994 12064 12157 I GMS_MM_Logger: Started. 05-03 14:34:34.005 7382 7506 W android.vendin: Accessing hidden method Lcom/android/org/conscrypt/ConscryptEngineSocket;->setUseSessionTickets(Z)V (max-target-q,core-platform-api, reflection, denied) 05-03 14:34:34.005 7382 7506 W android.vendin: Accessing hidden method Lcom/android/org/conscrypt/OpenSSLSocketImpl;->setUseSessionTickets(Z)V (max-target-q,core-platform-api, reflection, denied) 05-03 14:34:34.005 7382 7506 W android.vendin: Accessing hidden method Lcom/android/org/conscrypt/AbstractConscryptSocket;->setUseSessionTickets(Z)V (max-target-q, reflection, denied) 05-03 14:34:34.005 7382 7506 W android.vendin: Accessing hidden method Lcom/android/org/conscrypt/ConscryptEngineSocket;->setHostname(Ljava/lang/String;)V (max-target-q,core-platform-api, reflection, denied) 05-03 14:34:34.005 7382 7506 W android.vendin: Accessing hidden method Lcom/android/org/conscrypt/OpenSSLSocketImpl;->setHostname(Ljava/lang/String;)V (max-target-q,core-platform-api, reflection, denied) 05-03 14:34:34.005 7382 7506 W android.vendin: Accessing hidden method Lcom/android/org/conscrypt/AbstractConscryptSocket;->setHostname(Ljava/lang/String;)V (max-target-q, reflection, denied) 05-03 14:34:34.006 7382 7506 W android.vendin: Accessing hidden method Lcom/android/org/conscrypt/OpenSSLSocketImpl;->setAlpnProtocols([B)V (max-target-q,core-platform-api, reflection, denied) 05-03 14:34:34.006 7382 7506 W android.vendin: Accessing hidden method Lcom/android/org/conscrypt/AbstractConscryptSocket;->setAlpnProtocols([B)V (max-target-q, reflection, denied) 05-03 14:34:34.008 12128 12128 I Finsky:background: [2] tr.aM(20): Finished reading experiment flags from file [0tGUNCGIkbk4o6-fQ335kUYSKwVj4lusnHT-A5Dsy08]. 05-03 14:34:34.014 1035 1035 D Launcher.Model: package removed received com.economist.darwin.test 05-03 14:34:34.014 1035 1081 D PackageUpdatedTask: Removing app iconcom.economist.darwin.test 05-03 14:34:34.031 1035 1081 D ModelWriter: removing items from db 05-03 14:34:34.031 1035 1081 D ModelWriter: java.lang.Exception 05-03 14:34:34.031 1035 1081 D ModelWriter: at com.android.launcher3.model.ModelWriter.deleteItemsFromDatabase(SourceFile:8) 05-03 14:34:34.031 1035 1081 D ModelWriter: at com.android.launcher3.model.ModelWriter.deleteItemsFromDatabase(SourceFile:4) 05-03 14:34:34.031 1035 1081 D ModelWriter: at com.android.launcher3.model.BaseModelUpdateTask.deleteAndBindComponentsRemoved(SourceFile:1) 05-03 14:34:34.031 1035 1081 D ModelWriter: at com.android.launcher3.model.PackageUpdatedTask.execute(SourceFile:82) 05-03 14:34:34.031 1035 1081 D ModelWriter: at com.android.launcher3.model.BaseModelUpdateTask.run(SourceFile:2) 05-03 14:34:34.031 1035 1081 D ModelWriter: at android.os.Handler.handleCallback(Handler.java:938) 05-03 14:34:34.031 1035 1081 D ModelWriter: at android.os.Handler.dispatchMessage(Handler.java:99) 05-03 14:34:34.031 1035 1081 D ModelWriter: at android.os.Looper.loopOnce(Looper.java:201) 05-03 14:34:34.031 1035 1081 D ModelWriter: at android.os.Looper.loop(Looper.java:288) 05-03 14:34:34.031 1035 1081 D ModelWriter: at android.os.HandlerThread.run(HandlerThread.java:67) 05-03 14:34:34.067 12128 12128 I Finsky:background: [2] tr.aM(20): Finished reading experiment flags from file [Se-xCc4LzNyh3UrKMIU2sUf-PUKz4oQEbpKZDMuaFno]. 05-03 14:34:34.077 12064 12178 D CompatibilityChangeReporter: Compat change id reported: 160794467; UID 10097; state: ENABLED 05-03 14:34:34.080 12128 12128 I Finsky:background: [2] vgo.(3): vge - Registering in memory receiver for android.intent.action.PACKAGE_ADDED and android.intent.action.PACKAGE_REMOVED 05-03 14:34:34.095 521 3813 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10103; state: DISABLED 05-03 14:34:34.114 12128 12184 W ding:backgroun: Accessing hidden field Ljava/util/Collections$SynchronizedCollection;->mutex:Ljava/lang/Object; (max-target-o, reflection, denied) 05-03 14:34:34.115 12128 12184 W ding:backgroun: Accessing hidden method Ljava/util/Collections$SynchronizedSet;->(Ljava/util/Set;Ljava/lang/Object;)V (max-target-o, reflection, denied) 05-03 14:34:34.115 12128 12184 W ding:backgroun: Accessing hidden method Ljava/util/Collections$SynchronizedCollection;->(Ljava/util/Collection;Ljava/lang/Object;)V (max-target-o, reflection, denied) 05-03 14:34:34.157 521 553 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10097; state: DISABLED 05-03 14:34:34.189 7382 7506 W android.vendin: Accessing hidden method Lcom/android/org/conscrypt/OpenSSLSocketImpl;->getAlpnSelectedProtocol()[B (max-target-q,core-platform-api, reflection, denied) 05-03 14:34:34.189 7382 7506 W android.vendin: Accessing hidden method Lcom/android/org/conscrypt/AbstractConscryptSocket;->getAlpnSelectedProtocol()[B (max-target-q, reflection, denied) 05-03 14:34:34.247 12064 12185 I BlockstoreStorage: Clearing all the Blockstore Data for package com.economist.darwin [CONTEXT service_id=258 ] 05-03 14:34:34.248 12064 12185 I BlockstoreStorage: Clearing all the Blockstore Data for 1 packages [CONTEXT service_id=258 ] 05-03 14:34:34.294 12064 12181 I NamespaceManager: Package com.google.android.as name not found! Using module version. 05-03 14:34:34.295 12064 12181 I NamespaceManager: Package com.android.refreshratecontrol name not found! Using module version. 05-03 14:34:34.296 12064 12181 I NamespaceManager: Package com.google.android.as name not found! Using module version. 05-03 14:34:34.305 12064 12181 I NamespaceManager: Package com.google.oslo name not found! Using module version. 05-03 14:34:34.308 12064 12187 I Icing : Using regular flags by method 2 05-03 14:34:34.312 12064 12181 I NamespaceManager: Package com.android.media name not found! Using module version. 05-03 14:34:34.312 12064 12181 I NamespaceManager: Package com.android.media.swcodec name not found! Using module version. 05-03 14:34:34.313 12064 12181 I NamespaceManager: Package com.android.appsearch name not found! Using module version. 05-03 14:34:34.314 12064 12181 I NamespaceManager: Package com.android.neuralnetworks name not found! Using module version. 05-03 14:34:34.314 12064 12181 I NamespaceManager: Package com.google.android.odad name not found! Using module version. 05-03 14:34:34.316 12064 12181 I NamespaceManager: Package com.android.media name not found! Using module version. 05-03 14:34:34.320 12064 12181 I NamespaceManager: Package com.google.android.adservices name not found! Using module version. 05-03 14:34:34.322 12064 12181 I NamespaceManager: Package com.google.android.as.oss name not found! Using module version. 05-03 14:34:34.323 12064 12181 I NamespaceManager: Package 1 name not found! Using module version. 05-03 14:34:34.326 12064 12181 I NamespaceManager: Package com.google.android.sdksandbox name not found! Using module version. 05-03 14:34:34.334 7382 7444 E Finsky : [58] rju.a(16): ItemStore: getItems RPC failed. 05-03 14:34:34.338 521 717 D ConnectivityService: NetReassign [no changes] 05-03 14:34:34.345 521 624 I RoleService: Granting default roles... 05-03 14:34:34.362 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.contacts 05-03 14:34:34.364 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:34.444 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:34.450 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:34.468 12064 12205 I FA-SVC : App measurement initialized, version: 231516 05-03 14:34:34.472 12064 12203 I GmscoreIpa: Using regular flags by method 2 [CONTEXT service_id=255 ] 05-03 14:34:34.513 12064 12183 W DriveInitializer: Awaiting to be initialized 05-03 14:34:34.538 12064 12198 I Icing : Storage manager: low false usage 1.62MB avail 4.73GB capacity 5.81GB 05-03 14:34:34.553 12064 12188 W SQLiteLog: (28) double-quoted string literal: "com.economist.darwin" 05-03 14:34:34.593 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:34.626 12064 12219 I PinnedContentDownloader: Finished downloading pinned content. 05-03 14:34:34.667 12064 12189 I BlockstoreStorage: Clearing all the Blockstore Data for package com.economist.darwin.test [CONTEXT service_id=258 ] 05-03 14:34:34.667 12064 12189 I BlockstoreStorage: Clearing all the Blockstore Data for 1 packages [CONTEXT service_id=258 ] 05-03 14:34:34.686 12064 12187 W SQLiteLog: (28) double-quoted string literal: "com.economist.darwin.test" 05-03 14:34:34.709 12064 12200 I ContentMaintenance: Internal cache bytes used: 0; limit: 100000000; download size: 0 05-03 14:34:34.744 12064 12218 I Icing : IndexChimeraService.getServiceInterface callingPackage=com.google.android.gms componentName=AppsCorpus serviceId=32 05-03 14:34:34.747 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:34.751 12064 12157 I Icing : IndexChimeraService.getServiceInterface callingPackage=com.google.android.gms componentName=AppsCorpus serviceId=36 05-03 14:34:34.752 12064 12198 W native : W0000 00:00:1683104674.752348 12198 model-utils.cc:16] No CRC32, most likely an old model; skip CRC32 check 05-03 14:34:34.755 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:34.764 7094 7094 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.scheduler.ACTION_PROXY_SCHEDULE dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService } 05-03 14:34:34.764 7094 7094 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.scheduler.ACTION_PROXY_SCHEDULE dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService } 05-03 14:34:34.770 12064 12198 I native : I0000 00:00:1683104674.770633 12198 permission-checker.cc:81] Creating PermissionChecker based on ACL with config_version 1 having 10 permissions defined. 05-03 14:34:34.771 12064 12198 W native : W0000 00:00:1683104674.771034 12198 words-map.cc:36] INVALID_ARGUMENT: Empty URI 05-03 14:34:34.771 12064 12198 W native : W0000 00:00:1683104674.771080 12198 words-map.cc:40] WordsMap was not properly initialized 05-03 14:34:34.773 12064 12200 I RealtimeCacheCleanup: Beginning Realtime garbage collection. 05-03 14:34:34.774 12064 12200 I RealtimeCacheCleanup: Finished Realtime garbage collection. 05-03 14:34:34.812 7094 7094 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.icing.LIGHTWEIGHT_INDEX_SERVICE dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService } 05-03 14:34:34.812 7094 7094 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.icing.LIGHTWEIGHT_INDEX_SERVICE dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService } 05-03 14:34:34.834 12064 12198 I DynamiteModule: Considering local module com.google.android.gms.googlecertificates:7 and remote module com.google.android.gms.googlecertificates:7 05-03 14:34:34.834 12064 12198 I DynamiteModule: Selected local version of com.google.android.gms.googlecertificates 05-03 14:34:34.876 12064 12198 W Icing : Package com.google.android.googlequicksearchbox targets Android P and isn't allowed to use AppDataSearch indexing API 05-03 14:34:34.901 12064 12198 W Icing : Package com.google.android.gm targets Android P and isn't allowed to use AppDataSearch indexing API 05-03 14:34:34.914 780 780 D TileServices: Couldn't find tile for ComponentInfo{com.google.android.apps.wellbeing/com.google.android.apps.wellbeing.focusmode.quicksettings.FocusModeTileService} 05-03 14:34:34.919 521 3813 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10063; state: DISABLED 05-03 14:34:34.920 521 3813 D CompatibilityChangeReporter: Compat change id reported: 177438394; UID 10063; state: DISABLED 05-03 14:34:34.921 521 3813 D CompatibilityChangeReporter: Compat change id reported: 135772972; UID 10063; state: DISABLED 05-03 14:34:34.921 521 3813 D CompatibilityChangeReporter: Compat change id reported: 135754954; UID 10063; state: ENABLED 05-03 14:34:34.923 521 3813 I ActivityManager: Killing 11326:com.android.vending:instant_app_installer/u0a103 (adj 975): empty for 2577s 05-03 14:34:34.926 12064 12064 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.feedback.internal.IFeedbackService dat=chimera-action: cmp=com.google.android.gms/.chimera.GmsBoundBrokerService } 05-03 14:34:34.926 12064 12064 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.feedback.internal.IFeedbackService dat=chimera-action: cmp=com.google.android.gms/.chimera.GmsBoundBrokerService } 05-03 14:34:34.928 521 567 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10063; state: ENABLED 05-03 14:34:34.932 340 340 D Zygote : Forked child process 12231 05-03 14:34:34.933 521 568 I libprocessgroup: Successfully killed process cgroup uid 10103 pid 11326 in 0ms 05-03 14:34:34.940 340 340 I Zygote : Process 11326 exited due to signal 9 (Killed) 05-03 14:34:34.943 12231 12231 W oid.documentsu: Unexpected CPU variant for X86 using defaults: x86_64 05-03 14:34:34.948 12231 12231 E oid.documentsu: Not starting debugger since process cannot load the jdwp agent. 05-03 14:34:34.958 12064 12198 I Icing : updateResources: need to parse aqqj{com.google.android.gms} 05-03 14:34:34.982 521 567 W ActivityManager: Slow operation: 65ms so far, now at startProcess: done updating battery stats 05-03 14:34:34.982 521 567 W ActivityManager: Slow operation: 65ms so far, now at startProcess: building log message 05-03 14:34:34.982 521 567 I ActivityManager: Start proc 12231:com.google.android.documentsui/u0a63 for broadcast {com.google.android.documentsui/com.android.documentsui.PackageReceiver} 05-03 14:34:34.985 521 567 W ActivityManager: Slow operation: 68ms so far, now at startProcess: starting to update pids map 05-03 14:34:34.986 521 567 W ActivityManager: Slow operation: 69ms so far, now at startProcess: done updating pids map 05-03 14:34:35.004 12231 12231 D CompatibilityChangeReporter: Compat change id reported: 171979766; UID 10063; state: DISABLED 05-03 14:34:35.062 12231 12231 V GraphicsEnvironment: ANGLE Developer option for 'com.google.android.documentsui' set to: 'default' 05-03 14:34:35.062 12231 12231 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported. 05-03 14:34:35.063 12231 12231 D NetworkSecurityConfig: No Network Security Config specified, using platform default 05-03 14:34:35.063 12231 12231 D NetworkSecurityConfig: No Network Security Config specified, using platform default 05-03 14:34:35.178 521 2285 I ActivityManager: Killing 10215:com.google.android.apps.photos/u0a126 (adj 985): empty for 1877s 05-03 14:34:35.179 12231 12231 D DocumentsApplication: OverlayManager.setEnabled() result: true 05-03 14:34:35.248 12064 12064 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:35.248 12064 12064 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:35.256 521 568 I libprocessgroup: Successfully killed process cgroup uid 10126 pid 10215 in 70ms 05-03 14:34:35.264 521 2262 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10062; state: DISABLED 05-03 14:34:35.264 521 2262 D CompatibilityChangeReporter: Compat change id reported: 177438394; UID 10062; state: DISABLED 05-03 14:34:35.265 521 2262 D CompatibilityChangeReporter: Compat change id reported: 135772972; UID 10062; state: DISABLED 05-03 14:34:35.265 521 2262 D CompatibilityChangeReporter: Compat change id reported: 135754954; UID 10062; state: ENABLED 05-03 14:34:35.267 521 567 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10062; state: ENABLED 05-03 14:34:35.271 340 340 D Zygote : Forked child process 12257 05-03 14:34:35.272 340 340 I Zygote : Process 10215 exited due to signal 9 (Killed) 05-03 14:34:35.273 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.PACKAGE_FULLY_REMOVED dat=package:com.economist.darwin flg=0x5000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService (has extras) } 05-03 14:34:35.275 1491 3738 D MediaProvider: Deleted 0 Android/media items belonging to com.economist.darwin on /data/user/0/com.google.android.providers.media.module/databases/external.db 05-03 14:34:35.277 1491 3738 I MediaProvider: End Intent { act=android.intent.action.PACKAGE_FULLY_REMOVED dat=package:com.economist.darwin flg=0x5000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService (has extras) } 05-03 14:34:35.281 521 567 I ActivityManager: Start proc 12257:android.process.media/u0a62 for broadcast {com.android.providers.downloads/com.android.providers.downloads.DownloadReceiver} 05-03 14:34:35.289 12064 12064 D BoundBrokerSvc: onUnbind: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:35.290 12257 12257 W d.process.medi: Unexpected CPU variant for X86 using defaults: x86_64 05-03 14:34:35.299 521 2262 W ProcessStats: Tracking association SourceState{352e4d4 com.google.android.gms/10097 BTopFgs #23213} whose proc state 2 is better than process ProcessState{2648e03 com.android.vending/10103 pkg=com.android.vending (sub)} proc state 3 (0 skipped) 05-03 14:34:35.302 12257 12257 E d.process.medi: Not starting debugger since process cannot load the jdwp agent. 05-03 14:34:35.323 12257 12257 D CompatibilityChangeReporter: Compat change id reported: 171979766; UID 10062; state: ENABLED 05-03 14:34:35.331 12064 12198 I Icing : Internal init done: storage state 0 05-03 14:34:35.368 521 553 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10125; state: DISABLED 05-03 14:34:35.370 12064 12204 E WorkSourceUtil: Could not find package: com.economist.darwin 05-03 14:34:35.371 521 567 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10125; state: ENABLED 05-03 14:34:35.374 12064 12216 E WorkSourceUtil: Could not find package: com.economist.darwin.test 05-03 14:34:35.382 340 340 D Zygote : Forked child process 12274 05-03 14:34:35.388 521 567 I ActivityManager: Start proc 12274:com.google.android.apps.docs/u0a125 for content provider {com.google.android.apps.docs/com.google.android.apps.docs.storagebackend.StorageBackendContentProvider} 05-03 14:34:35.387 12257 12257 V GraphicsEnvironment: ANGLE Developer option for 'com.android.providers.downloads' set to: 'default' 05-03 14:34:35.411 12064 12204 I Icing : IndexChimeraService.getServiceInterface callingPackage=com.google.android.gms componentName=ContactsIndexer serviceId=33 05-03 14:34:35.412 12257 12257 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported. 05-03 14:34:35.419 12257 12257 D NetworkSecurityConfig: No Network Security Config specified, using platform default 05-03 14:34:35.419 12257 12257 D NetworkSecurityConfig: No Network Security Config specified, using platform default 05-03 14:34:35.430 12064 12157 I Icing : IndexChimeraService.getServiceInterface callingPackage=com.google.android.gms componentName=null serviceId=30 05-03 14:34:35.430 12274 12274 W droid.apps.doc: Unexpected CPU variant for X86 using defaults: x86_64 05-03 14:34:35.433 521 588 I PackageManager: Integrity check passed for file:///data/app/vmdl2114676648.tmp 05-03 14:34:35.439 12274 12274 E droid.apps.doc: Not starting debugger since process cannot load the jdwp agent. 05-03 14:34:35.446 12064 12198 I Icing : Post-init done 05-03 14:34:35.446 12064 12198 I Icing : doRemovePackageData com.economist.darwin 05-03 14:34:35.447 12064 12198 I Icing : doRemovePackageData com.economist.darwin.test 05-03 14:34:35.459 521 763 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10125; state: DISABLED 05-03 14:34:35.480 12274 12274 D CompatibilityChangeReporter: Compat change id reported: 171979766; UID 10125; state: DISABLED 05-03 14:34:35.484 521 588 I PackageManager: Permission ownership changed. Updating all permissions. 05-03 14:34:35.542 12274 12274 D nativeloader: classloader namespace configured for unbundled product apk. library_path=/product/app/Drive/lib/x86_64:/product/app/Drive/Drive.apk!/lib/x86_64:/product/lib64:/system/product/lib64 05-03 14:34:35.607 521 555 W Looper : Slow dispatch took 116ms android.fg h=android.os.Handler c= m=0 05-03 14:34:35.614 521 588 I PackageDexOptimizer: Running dexopt (dexoptNeeded=1) on: /data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk pkg=com.economist.darwin isa=x86_64 dexoptFlags=boot_complete,profile_guided,public,enable_hidden_api_checks targetFilter=speed-profile oatDir=/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/oat classLoaderContext=PCL[]{} 05-03 14:34:35.614 447 535 V installd: DexInv: --- BEGIN '/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk' --- 05-03 14:34:35.614 447 535 V installd: Running /apex/com.android.art/bin/dex2oat64 in=base.apk out=/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/oat/x86_64/base.odex 05-03 14:34:35.615 12274 12274 V GraphicsEnvironment: ANGLE Developer option for 'com.google.android.apps.docs' set to: 'default' 05-03 14:34:35.615 12274 12274 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported. 05-03 14:34:35.618 12274 12274 D NetworkSecurityConfig: Using Network Security Config from resource network_security_config debugBuild: false 05-03 14:34:35.620 12274 12274 D NetworkSecurityConfig: Using Network Security Config from resource network_security_config debugBuild: false 05-03 14:34:35.637 12291 12291 W dex2oat64: Unexpected CPU variant for X86 using defaults: x86_64 05-03 14:34:35.637 12291 12291 W dex2oat64: Mismatch between dex2oat instruction set features to use (ISA: X86_64 Feature string: -ssse3,-sse4.1,-sse4.2,-avx,-avx2,-popcnt) and those from CPP defines (ISA: X86_64 Feature string: ssse3,sse4.1,sse4.2,-avx,-avx2,popcnt) for the command line: 05-03 14:34:35.637 12291 12291 W dex2oat64: /apex/com.android.art/bin/dex2oat64 --zip-fd=7 --zip-location=base.apk --oat-fd=8 --oat-location=/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/oat/x86_64/base.odex --input-vdex-fd=-1 --output-vdex-fd=9 --swap-fd=10 --classpath-dir=/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg== --class-loader-context=PCL[]{} --compact-dex-level=none --instruction-set=x86_64 --instruction-set-features=default --instruction-set-variant=x86_64 --compiler-filter=speed-profile --compilation-reason=install --max-image-block-size=524288 --resolve-startup-const-strings=true --generate-mini-debug-info --runtime-arg -Xtarget-sdk-version:33 --runtime-arg -Xhidden-api-policy:enabled --runtime-arg -Xms64m --runtime-arg -Xmx512m 05-03 14:34:35.637 12291 12291 I dex2oat64: /apex/com.android.art/bin/dex2oat64 --input-vdex-fd=-1 --output-vdex-fd=9 --classpath-dir=/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg== --class-loader-context=PCL[]{} --compact-dex-level=none --compiler-filter=speed-profile --compilation-reason=install --max-image-block-size=524288 --resolve-startup-const-strings=true --generate-mini-debug-info 05-03 14:34:35.658 1491 1516 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.economist.darwin. Reason: op android:read_external_storage 05-03 14:34:35.658 521 555 V StorageManagerService: Package null does not have legacy storage 05-03 14:34:35.702 12064 12198 W Icing : No valid account for uploading user actions 05-03 14:34:35.708 12064 12157 I Icing : IndexChimeraService.getServiceInterface callingPackage=com.google.android.gms componentName=null serviceId=36 05-03 14:34:35.732 521 1597 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10060; state: DISABLED 05-03 14:34:35.732 521 1597 D CompatibilityChangeReporter: Compat change id reported: 177438394; UID 10060; state: DISABLED 05-03 14:34:35.732 521 1597 D CompatibilityChangeReporter: Compat change id reported: 135772972; UID 10060; state: DISABLED 05-03 14:34:35.732 521 1597 D CompatibilityChangeReporter: Compat change id reported: 135754954; UID 10060; state: ENABLED 05-03 14:34:35.732 521 567 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10060; state: ENABLED 05-03 14:34:35.738 340 340 D Zygote : Forked child process 12297 05-03 14:34:35.744 521 567 I ActivityManager: Start proc 12297:android.process.acore/u0a60 for content provider {com.android.providers.contacts/com.android.providers.contacts.ContactsProvider2} 05-03 14:34:35.751 12297 12297 W d.process.acor: Unexpected CPU variant for X86 using defaults: x86_64 05-03 14:34:35.755 12297 12297 E d.process.acor: Not starting debugger since process cannot load the jdwp agent. 05-03 14:34:35.774 12064 12198 I Icing : Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0 05-03 14:34:35.780 12274 12312 W droid.apps.doc: Accessing hidden field Ljava/util/Collections$SynchronizedCollection;->mutex:Ljava/lang/Object; (max-target-o, reflection, denied) 05-03 14:34:35.780 12274 12312 W droid.apps.doc: Accessing hidden method Ljava/util/Collections$SynchronizedSet;->(Ljava/util/Set;Ljava/lang/Object;)V (max-target-o, reflection, denied) 05-03 14:34:35.780 12274 12312 W droid.apps.doc: Accessing hidden method Ljava/util/Collections$SynchronizedCollection;->(Ljava/util/Collection;Ljava/lang/Object;)V (max-target-o, reflection, denied) 05-03 14:34:35.783 521 553 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10060; state: DISABLED 05-03 14:34:35.795 12064 12198 W Icing : No valid account for uploading user actions 05-03 14:34:35.816 12064 12198 I Icing : Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0 05-03 14:34:35.824 12297 12297 D CompatibilityChangeReporter: Compat change id reported: 171979766; UID 10060; state: ENABLED 05-03 14:34:35.827 7094 7094 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.phenotype.service.START pkg=com.google.android.gms } 05-03 14:34:35.827 7094 7094 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.phenotype.service.START pkg=com.google.android.gms } 05-03 14:34:35.827 12064 12198 W Icing : No valid account for uploading user actions 05-03 14:34:35.835 521 1596 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.packagemonitor.impl.PackageMonitorReceiverImpl$RegisteredReceiver 05-03 14:34:35.836 12297 12297 V GraphicsEnvironment: ANGLE Developer option for 'com.android.providers.contacts' set to: 'default' 05-03 14:34:35.836 12297 12297 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported. 05-03 14:34:35.836 12297 12297 D NetworkSecurityConfig: No Network Security Config specified, using platform default 05-03 14:34:35.836 12297 12297 D NetworkSecurityConfig: No Network Security Config specified, using platform default 05-03 14:34:35.840 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.packagemonitor.backgroundimpl.BackgroundPackageMonitorReceiverImpl$RegisteredReceiver 05-03 14:34:35.840 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.instantapps.appmanagement.InstantAppRemoveMonitor 05-03 14:34:35.840 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.google.android.gms/.games.chimera.GamesSystemBroadcastReceiverProxy 05-03 14:34:35.841 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver 05-03 14:34:35.841 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_REMOVED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.google.android.googlequicksearchbox/com.google.android.apps.gsa.googlequicksearchbox.GelStubAppWatcher 05-03 14:34:35.902 12064 12198 I Icing : Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0 05-03 14:34:35.903 521 566 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10115; state: DISABLED 05-03 14:34:36.017 12064 12198 W Icing : No valid account for uploading user actions 05-03 14:34:36.034 7382 7382 I Finsky : [2] vgr.g(0): vgr - Received: android.intent.action.PACKAGE_FULLY_REMOVED, [po1Su8iV47FnK8_AdNTaMWnCBz_mL7KdoDwh7nTB-UU] 05-03 14:34:36.039 7382 7382 I Finsky : [2] vhi.s(5): Package no longer installed: com.economist.darwin.test 05-03 14:34:36.041 7382 7382 I Finsky : [2] vgr.g(0): vgr - Deduping intent android.intent.action.PACKAGE_FULLY_REMOVED 05-03 14:34:36.041 7382 12030 I Finsky : [196] nix.run(2): Frosting DB delete succeeded: false 05-03 14:34:36.050 12064 12198 I Icing : Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0 05-03 14:34:36.080 12297 12297 I ContactsPerf: VoicemailContentProvider.onCreate start 05-03 14:34:36.082 12274 12318 I GAv4 : Google Analytics 212.4.12 is starting up. To enable debug logging on a device run: 05-03 14:34:36.082 12274 12318 I GAv4 : adb shell setprop log.tag.GAv4 DEBUG 05-03 14:34:36.082 12274 12318 I GAv4 : adb logcat -s GAv4 05-03 14:34:36.085 12297 12297 I ContactsPerf: VoicemailContentProvider.onCreate finish 05-03 14:34:36.094 12274 12318 D CompatibilityChangeReporter: Compat change id reported: 160794467; UID 10125; state: DISABLED 05-03 14:34:36.100 12297 12326 D ActivityThread: Loading provider call_log_shadow: com.android.providers.contacts.ShadowCallLogProvider 05-03 14:34:36.102 12274 12329 W GAv4 : CampaignTrackingReceiver is not registered, not exported or is disabled. Installation campaign tracking is not possible. See http://goo.gl/8Rd3yj for instructions. 05-03 14:34:36.131 521 2230 D CountryDetector: The first listener is added 05-03 14:34:36.131 12297 12297 I ContactsDatabaseHelper: updateUseStrictPhoneNumberComparison: US 05-03 14:34:36.160 12297 12326 I CallLogProvider: Syncing call composer pics -- source user=0, isShadow=true, forAllUser=false 05-03 14:34:36.161 12297 12326 I CallLogProvider: Fetching list of Uris to sync 05-03 14:34:36.185 12274 12335 W FieldDefinition: [RxCachedThreadScheduler-3] Ignoring isIndexed constraint as field also has uniqueness constraint (on just this field, and therefore SQLite will have to create an index on that. For field: blj$a@c6074e5 05-03 14:34:36.260 12064 12198 W Icing : No valid account for uploading user actions 05-03 14:34:36.275 12297 12342 D ContactsDatabaseHelper: WAL enabled for contacts2.db: true 05-03 14:34:36.283 12064 12198 I Icing : Usage reports ok 0, Failed Usage reports 0, indexed 0, rejected 0 05-03 14:34:36.338 12274 12339 E CelloDelayUploadFixer: [RxCachedThreadScheduler-7] Cleanup delay upload complete 05-03 14:34:36.379 12231 12250 I ProvidersCache: Provider returned no roots. Possibly naughty: com.google.android.apps.docs.storage 05-03 14:34:36.379 12297 12326 I CallLogProvider: Will sync following Uris:[] 05-03 14:34:36.379 12297 12326 I CallLogProvider: Syncing call composer pics -- got [] 05-03 14:34:36.382 12274 12334 E NotificationAccessor: Cannot delete notification channel. Channel not found. 05-03 14:34:36.401 12274 12334 E NotificationAccessor: Cannot delete notification channel. Channel not found. 05-03 14:34:36.409 12274 12334 E NotificationAccessor: Cannot delete notification channel. Channel not found. 05-03 14:34:36.413 12064 12198 I Icing : Indexing com.google.android.gms-apps from com.google.android.gms 05-03 14:34:36.443 12064 12198 I Icing : Indexing done com.google.android.gms-apps 05-03 14:34:36.547 12297 12342 I ContactLocale: AddressBook Labels [[en_US]]: […, A, B, C, D, E, F, G, H, I, J, K, L, M, N, O, P, Q, R, S, T, U, V, W, X, Y, Z, Α, Β, Γ, Δ, Ε, Ζ, Η, Θ, Ι, Κ, Λ, Μ, Ν, Ξ, Ο, Π, Ρ, Σ, Τ, Υ, Φ, Χ, Ψ, Ω, …, А, Б, В, Г, Д, Ђ, Е, Є, Ж, З, И, І, Й, Ј, К, Л, Љ, М, Н, Њ, О, П, Р, С, Т, Ћ, У, Ф, Х, Ц, Ч, Џ, Ш, Щ, Ю, Я, …, א, ב, ג, ד, ה, ו, ז, ח, ט, י, כ, ל, מ, נ, ס, ע, פ, צ, ק, ר, ש, ת, …, ا, ب, ت, ث, ج, ح, خ, د, ذ, ر, ز, س, ش, ص, ض, ط, ظ, ع, غ, ف, ق, ك, ل, م, ن, ه, و, ي, …, ก, ข, ฃ, ค, ฅ, ฆ, ง, จ, ฉ, ช, ซ, ฌ, ญ, ฎ, ฏ, ฐ, ฑ, ฒ, ณ, ด, ต, ถ, ท, ธ, น, บ, ป, ผ, ฝ, พ, ฟ, ภ, ม, ย, ร, ฤ, ล, ฦ, ว, ศ, ษ, ส, ห, ฬ, อ, ฮ, …, ㄱ, ㄴ, ㄷ, ㄹ, ㅁ, ㅂ, ㅅ, ㅇ, ㅈ, ㅊ, ㅋ, ㅌ, ㅍ, ㅎ, …, あ, か, さ, た, な, は, ま, や, ら, わ, #, …] 05-03 14:34:36.599 12297 12342 D ContactsDatabaseHelper: WAL enabled for profile.db: false 05-03 14:34:36.711 7094 7094 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.pseudonymous.service.START pkg=com.google.android.gms } 05-03 14:34:36.711 7094 7094 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.pseudonymous.service.START pkg=com.google.android.gms } 05-03 14:34:36.768 521 763 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10121; state: DISABLED 05-03 14:34:36.769 521 567 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10121; state: ENABLED 05-03 14:34:36.775 340 340 D Zygote : Forked child process 12366 05-03 14:34:36.778 521 567 I ActivityManager: Start proc 12366:com.google.android.gm/u0a121 for content provider {com.google.android.gm/com.android.exchange.provider.ExchangeDirectoryProvider} 05-03 14:34:36.788 12366 12366 W ogle.android.g: Unexpected CPU variant for X86 using defaults: x86_64 05-03 14:34:36.795 12366 12366 E ogle.android.g: Not starting debugger since process cannot load the jdwp agent. 05-03 14:34:36.806 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.sync.syncadapter.DocsSyncAdapterService 05-03 14:34:36.817 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.drive.directsharing.ChooserTargetService 05-03 14:34:36.834 12366 12366 D CompatibilityChangeReporter: Compat change id reported: 171979766; UID 10121; state: DISABLED 05-03 14:34:36.864 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.shared.documentcreation.WidgetDocumentCreatorActivity 05-03 14:34:36.875 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.shared.documentcreation.ExternalDocumentCreatorActivity 05-03 14:34:36.875 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.shared.widgets.NewDocumentWidgetActivity 05-03 14:34:36.876 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.shared.export.FileContentProvider 05-03 14:34:36.876 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.shared.copypaste.AndroidClipboardContentProvider 05-03 14:34:36.876 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.shared.copypaste.AndroidImageContentProvider 05-03 14:34:36.877 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.shared.inserttool.clipboard.InsertToolClipboardContentProvider 05-03 14:34:36.877 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.shared.inserttool.clipboard.InsertToolImageContentProvider 05-03 14:34:36.878 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.kix.conversion.KixOnlineImportActivity 05-03 14:34:36.878 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.ritz.conversion.RitzOnlineImportActivity 05-03 14:34:36.878 12274 12287 W GmsIpProtection: [Binder:12274_1] Error changing state for com.google.android.apps.docs.editors.punch.conversion.PunchOnlineImportActivity 05-03 14:34:36.897 12366 12366 D nativeloader: classloader namespace configured for unbundled product apk. library_path=/product/app/PrebuiltGmail/lib/x86_64:/product/app/PrebuiltGmail/PrebuiltGmail.apk!/lib/x86_64:/product/lib64:/system/product/lib64 05-03 14:34:36.911 12366 12366 V GraphicsEnvironment: ANGLE Developer option for 'com.google.android.gm' set to: 'default' 05-03 14:34:36.911 12366 12366 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported. 05-03 14:34:36.912 12366 12366 D NetworkSecurityConfig: Using Network Security Config from resource network_security_config debugBuild: false 05-03 14:34:36.913 12366 12366 D NetworkSecurityConfig: Using Network Security Config from resource network_security_config debugBuild: false 05-03 14:34:36.990 521 1597 I ActivityManager: Background started FGS: Allowed [callingPackage: com.google.android.apps.wellbeing; callingUid: 10100; uidState: RCVR; intent: Intent { cmp=com.google.android.apps.wellbeing/com.google.apps.tiktok.concurrent.InternalForegroundService (has extras) }; code:BACKGROUND_ACTIVITY_PERMISSION; tempAllowListReason:; targetSdkVersion:30; callerTargetSdkVersion:30; startForegroundCount:0; bindFromPackage:null] 05-03 14:34:37.016 521 566 I ActivityManager: Deferring FGS notification in legacy app com.google.android.apps.wellbeing/u0a100 : Notification(channel=app_limit_updates shortcut=null contentView=null vibrate=null sound=null defaults=0x0 flags=0x162 color=0xff0b57d0 category=service vis=PRIVATE) 05-03 14:34:37.454 521 1597 W PermissionManagerService: Datasource android.content.AttributionSource@632963d7 protecting data with platform defined runtime permission android.permission.READ_CONTACTS while not having android.permission.UPDATE_APP_OPS_STATS 05-03 14:34:37.539 12366 12389 W ogle.android.g: Accessing hidden field Ljava/util/Collections$SynchronizedCollection;->mutex:Ljava/lang/Object; (max-target-o, reflection, denied) 05-03 14:34:37.539 12366 12389 W ogle.android.g: Accessing hidden method Ljava/util/Collections$SynchronizedSet;->(Ljava/util/Set;Ljava/lang/Object;)V (max-target-o, reflection, denied) 05-03 14:34:37.539 12366 12389 W ogle.android.g: Accessing hidden method Ljava/util/Collections$SynchronizedCollection;->(Ljava/util/Collection;Ljava/lang/Object;)V (max-target-o, reflection, denied) 05-03 14:34:37.546 12064 12102 I PeopleGalProvider: Gal directories started. 05-03 14:34:37.555 12064 12102 I PeopleGalProvider: Method 1 completed. 05-03 14:34:37.561 12064 12102 I PeopleGalProvider: Method 1 finished successfully. 05-03 14:34:37.636 12297 12342 I ContactDirectoryManager: deleted 0 stale rows which don't have any relevant directory 05-03 14:34:37.638 12297 12342 I ContactDirectoryManager: Discovered 0 contact directories in 884ms 05-03 14:34:37.642 12064 12102 D CompatibilityChangeReporter: Compat change id reported: 150939131; UID 10097; state: ENABLED 05-03 14:34:37.819 12064 12064 D BoundBrokerSvc: onBind: Intent { act=com.google.android.mobstore.service.START pkg=com.google.android.gms } 05-03 14:34:37.819 12064 12064 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.mobstore.service.START pkg=com.google.android.gms } 05-03 14:34:37.883 12297 12306 I d.process.acor: Background young concurrent copying GC freed 15125(829KB) AllocSpace objects, 2(40KB) LOS objects, 91% free, 2228KB/26MB, paused 18.634ms,81us total 128.518ms 05-03 14:34:37.906 780 780 D TileServices: Couldn't find tile for ComponentInfo{com.google.android.apps.wellbeing/com.google.android.apps.wellbeing.focusmode.quicksettings.FocusModeTileService} 05-03 14:34:37.920 521 553 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10063; state: DISABLED 05-03 14:34:37.947 12064 12064 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:37.947 12064 12064 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:37.971 12366 12399 I org.webrtc.Logging: CrashStartupListener: Checking conference crashes for 0 account(s). 05-03 14:34:37.981 12064 12064 D BoundBrokerSvc: onUnbind: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:38.016 521 947 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10062; state: DISABLED 05-03 14:34:38.023 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.PACKAGE_FULLY_REMOVED dat=package:com.economist.darwin.test flg=0x5000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService (has extras) } 05-03 14:34:38.031 7094 10370 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.icing.proxy.IcingInternalCorporaUpdateService started execution. cause:9 exec_start_elapsed_seconds: 3938 [CONTEXT service_id=218 ] 05-03 14:34:38.024 1491 3738 D MediaProvider: Deleted 0 Android/media items belonging to com.economist.darwin.test on /data/user/0/com.google.android.providers.media.module/databases/external.db 05-03 14:34:38.062 1491 3738 I MediaProvider: End Intent { act=android.intent.action.PACKAGE_FULLY_REMOVED dat=package:com.economist.darwin.test flg=0x5000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService (has extras) } 05-03 14:34:38.065 7094 7199 I FontLog : Received query Noto Color Emoji Compat, URI content://com.google.android.gms.fonts [CONTEXT service_id=132 ] 05-03 14:34:38.065 7094 7199 I FontLog : Query [Noto Color Emoji Compat] resolved to {Noto Color Emoji Compat, wdth 100.0, wght 400, ital 0.0, bestEffort false} [CONTEXT service_id=132 ] 05-03 14:34:38.097 12366 12417 W System : ClassLoader referenced unknown path: 05-03 14:34:38.097 12064 12201 W GmsTaskScheduler: com.google.android.gms.icing.service.IcingGcmTaskChimeraService is not available. This may cause the task to be lost. 05-03 14:34:38.115 12366 12417 W DynamiteModule: Local module descriptor class for com.google.android.gms.crash not found. 05-03 14:34:38.116 7094 7199 I FontLog : Fetch {Noto Color Emoji Compat, wdth 100.0, wght 400, ital 0.0, bestEffort false} end status Status{statusCode=SUCCESS, resolution=null} [CONTEXT service_id=132 ] 05-03 14:34:38.164 7094 11986 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.icing.proxy.IcingInternalCorporaUpdateService finished executing. cause:9 result: 1 elapsed_millis: 206 uptime_millis: 206 exec_start_elapsed_seconds: 3938 [CONTEXT service_id=218 ] 05-03 14:34:38.171 7094 7173 I FontLog : Pulling font file for id = 11, cache size = 6 [CONTEXT service_id=132 ] 05-03 14:34:38.202 12366 12366 W Gmail : AccountUtils:For b/73513912, load account content://com.google.android.gm.sapi/account.-2137146394 from MailAppProvider. Is account valid result: 1. 05-03 14:34:38.204 12366 12366 W Gmail : AccountUtils:For b/73513912, load account content://com.google.android.gm.email.provider/account.-437561172?suppress_combined%3Dtrue from MailAppProvider. Is account valid result: 1. 05-03 14:34:38.256 12366 12392 D CompatibilityChangeReporter: Compat change id reported: 160794467; UID 10121; state: DISABLED 05-03 14:34:38.311 7094 7094 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.auth.account.workaccount.START dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService } 05-03 14:34:38.311 7094 7094 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.auth.account.workaccount.START dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService } 05-03 14:34:38.326 7094 7173 E AuthPII : [GoogleAccountDataServiceImpl] getToken() -> BAD_AUTHENTICATION. App: com.google.android.gms, Service: oauth2:https://www.googleapis.com/auth/userinfo.email https://www.googleapis.com/auth/account.capabilities https://www.googleapis.com/auth/account.service_flags 05-03 14:34:38.326 7094 7173 E AuthPII : aape: Long live credential not available. 05-03 14:34:38.326 7094 7173 E AuthPII : at lwc.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):8) 05-03 14:34:38.326 7094 7173 E AuthPII : at lwj.b(:com.google.android.gms@231516047@23.15.16 (190800-527344967):3) 05-03 14:34:38.326 7094 7173 E AuthPII : at lug.m(:com.google.android.gms@231516047@23.15.16 (190800-527344967):47) 05-03 14:34:38.326 7094 7173 E AuthPII : at lti.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):24) 05-03 14:34:38.326 7094 7173 E AuthPII : at ltn.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.326 7094 7173 E AuthPII : at lxb.b(:com.google.android.gms@231516047@23.15.16 (190800-527344967):3) 05-03 14:34:38.326 7094 7173 E AuthPII : at lxb.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):7) 05-03 14:34:38.326 7094 7173 E AuthPII : at lsn.hasFeatures(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.accounts.AbstractAccountAuthenticator$Transport.hasFeatures(AbstractAccountAuthenticator.java:312) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.accounts.IAccountAuthenticator$Stub.onTransact(IAccountAuthenticator.java:345) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.os.Binder.transact(Binder.java:1064) 05-03 14:34:38.326 7094 7173 E AuthPII : at atig.onTransact(:com.google.android.gms@231516047@23.15.16 (190800-527344967):1) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.os.Binder.transact(Binder.java:1064) 05-03 14:34:38.326 7094 7173 E AuthPII : at anmc.onTransact(:com.google.android.gms@231516047@23.15.16 (190800-527344967):25) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.os.Binder.execTransactInternal(Binder.java:1184) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.os.Binder.execTransact(Binder.java:1143) 05-03 14:34:38.326 7094 7173 E AuthPII : Message is Long live credential not available. 05-03 14:34:38.326 7094 7173 E AuthPII : aape: Long live credential not available. 05-03 14:34:38.326 7094 7173 E AuthPII : at lwc.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):8) 05-03 14:34:38.326 7094 7173 E AuthPII : at lwj.b(:com.google.android.gms@231516047@23.15.16 (190800-527344967):3) 05-03 14:34:38.326 7094 7173 E AuthPII : at lug.m(:com.google.android.gms@231516047@23.15.16 (190800-527344967):47) 05-03 14:34:38.326 7094 7173 E AuthPII : at lti.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):24) 05-03 14:34:38.326 7094 7173 E AuthPII : at ltn.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.326 7094 7173 E AuthPII : at lxb.b(:com.google.android.gms@231516047@23.15.16 (190800-527344967):3) 05-03 14:34:38.326 7094 7173 E AuthPII : at lxb.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):7) 05-03 14:34:38.326 7094 7173 E AuthPII : at lsn.hasFeatures(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.accounts.AbstractAccountAuthenticator$Transport.hasFeatures(AbstractAccountAuthenticator.java:312) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.accounts.IAccountAuthenticator$Stub.onTransact(IAccountAuthenticator.java:345) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.os.Binder.transact(Binder.java:1064) 05-03 14:34:38.326 7094 7173 E AuthPII : at atig.onTransact(:com.google.android.gms@231516047@23.15.16 (190800-527344967):1) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.os.Binder.transact(Binder.java:1064) 05-03 14:34:38.326 7094 7173 E AuthPII : at anmc.onTransact(:com.google.android.gms@231516047@23.15.16 (190800-527344967):25) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.os.Binder.execTransactInternal(Binder.java:1184) 05-03 14:34:38.326 7094 7173 E AuthPII : at android.os.Binder.execTransact(Binder.java:1143) 05-03 14:34:38.327 7094 7173 W Auth : [AccountStateSyncher] Unable to look up account state from server: couldn't fetch accessToken [CONTEXT service_id=153 ] 05-03 14:34:38.328 7094 7173 W Auth : [GaiaServicesFlagManager] Services not available! [CONTEXT service_id=153 ] 05-03 14:34:38.329 7094 7102 I .gms.persisten: Background concurrent copying GC freed 436468(19MB) AllocSpace objects, 20(2904KB) LOS objects, 49% free, 16MB/32MB, paused 855us,140us total 352.868ms 05-03 14:34:38.329 7094 7102 W .gms.persisten: Reducing the number of considered missed Gc histogram windows from 191 to 100 05-03 14:34:38.340 7094 7173 I FontLog : Pulling font file for id = 11, cache size = 6 [CONTEXT service_id=132 ] 05-03 14:34:38.364 12366 12417 I FirebaseCrashApiImpl: FirebaseCrashApiImpl created by ClassLoader dalvik.system.DelegateLastClassLoader[DexPathList[[zip file "/data/user_de/0/com.google.android.gms/app_chimera/m/0000000f/DynamiteModulesC.apk"],nativeLibraryDirectories=[/data/user_de/0/com.google.android.gms/app_chimera/m/0000000f/DynamiteModulesC.apk!/lib/x86_64, /data/user_de/0/com.google.android.gms/app_chimera/m/0000000f/DynamiteModulesC.apk!/lib/arm64-v8a, /system/lib64, /system_ext/lib64]]] 05-03 14:34:38.380 7094 9357 E AuthPII : [GoogleAccountDataServiceImpl] getToken() -> BAD_AUTHENTICATION. App: com.google.android.gms, Service: oauth2:https://www.googleapis.com/auth/userinfo.email https://www.googleapis.com/auth/account.capabilities https://www.googleapis.com/auth/account.service_flags 05-03 14:34:38.380 7094 9357 E AuthPII : aape: Long live credential not available. 05-03 14:34:38.380 7094 9357 E AuthPII : at lwc.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):8) 05-03 14:34:38.380 7094 9357 E AuthPII : at lwj.b(:com.google.android.gms@231516047@23.15.16 (190800-527344967):3) 05-03 14:34:38.380 7094 9357 E AuthPII : at lug.m(:com.google.android.gms@231516047@23.15.16 (190800-527344967):47) 05-03 14:34:38.380 7094 9357 E AuthPII : at lti.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):24) 05-03 14:34:38.380 7094 9357 E AuthPII : at ltn.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.380 7094 9357 E AuthPII : at lxb.b(:com.google.android.gms@231516047@23.15.16 (190800-527344967):3) 05-03 14:34:38.380 7094 9357 E AuthPII : at lxb.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):7) 05-03 14:34:38.380 7094 9357 E AuthPII : at lsn.hasFeatures(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.accounts.AbstractAccountAuthenticator$Transport.hasFeatures(AbstractAccountAuthenticator.java:312) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.accounts.IAccountAuthenticator$Stub.onTransact(IAccountAuthenticator.java:345) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.os.Binder.transact(Binder.java:1064) 05-03 14:34:38.380 7094 9357 E AuthPII : at atig.onTransact(:com.google.android.gms@231516047@23.15.16 (190800-527344967):1) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.os.Binder.transact(Binder.java:1064) 05-03 14:34:38.380 7094 9357 E AuthPII : at anmc.onTransact(:com.google.android.gms@231516047@23.15.16 (190800-527344967):25) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.os.Binder.execTransactInternal(Binder.java:1184) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.os.Binder.execTransact(Binder.java:1143) 05-03 14:34:38.380 7094 9357 E AuthPII : Message is Long live credential not available. 05-03 14:34:38.380 7094 9357 E AuthPII : aape: Long live credential not available. 05-03 14:34:38.380 7094 9357 E AuthPII : at lwc.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):8) 05-03 14:34:38.380 7094 9357 E AuthPII : at lwj.b(:com.google.android.gms@231516047@23.15.16 (190800-527344967):3) 05-03 14:34:38.380 7094 9357 E AuthPII : at lug.m(:com.google.android.gms@231516047@23.15.16 (190800-527344967):47) 05-03 14:34:38.380 7094 9357 E AuthPII : at lti.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):24) 05-03 14:34:38.380 7094 9357 E AuthPII : at ltn.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.380 7094 9357 E AuthPII : at lxb.b(:com.google.android.gms@231516047@23.15.16 (190800-527344967):3) 05-03 14:34:38.380 7094 9357 E AuthPII : at lxb.a(:com.google.android.gms@231516047@23.15.16 (190800-527344967):7) 05-03 14:34:38.380 7094 9357 E AuthPII : at lsn.hasFeatures(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.accounts.AbstractAccountAuthenticator$Transport.hasFeatures(AbstractAccountAuthenticator.java:312) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.accounts.IAccountAuthenticator$Stub.onTransact(IAccountAuthenticator.java:345) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.os.Binder.transact(Binder.java:1064) 05-03 14:34:38.380 7094 9357 E AuthPII : at atig.onTransact(:com.google.android.gms@231516047@23.15.16 (190800-527344967):1) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.os.Binder.transact(Binder.java:1064) 05-03 14:34:38.380 7094 9357 E AuthPII : at anmc.onTransact(:com.google.android.gms@231516047@23.15.16 (190800-527344967):25) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.os.Binder.execTransactInternal(Binder.java:1184) 05-03 14:34:38.380 7094 9357 E AuthPII : at android.os.Binder.execTransact(Binder.java:1143) 05-03 14:34:38.381 7094 9357 W Auth : [AccountStateSyncher] Unable to look up account state from server: couldn't fetch accessToken [CONTEXT service_id=153 ] 05-03 14:34:38.381 7094 9357 W Auth : [GaiaServicesFlagManager] Services not available! [CONTEXT service_id=153 ] 05-03 14:34:38.410 12366 12418 I DynamiteModule: Considering local module com.google.android.gms.flags:3 and remote module com.google.android.gms.flags:3 05-03 14:34:38.410 12366 12418 I DynamiteModule: Selected local version of com.google.android.gms.flags 05-03 14:34:38.425 7094 7189 E bujr : Phenotype API error. Event # ealm@403765f9, EventCode: 5 [CONTEXT service_id=51 ] 05-03 14:34:38.425 7094 7189 E bujr : buio: 29501: Stale snapshot for com.google.android.libraries.communications.conference.device#com.google.android.gm(change count changed - expected 5 but was 3) 05-03 14:34:38.425 7094 7189 E bujr : at bujw.b(:com.google.android.gms@231516047@23.15.16 (190800-527344967):16) 05-03 14:34:38.425 7094 7189 E bujr : at buju.g(:com.google.android.gms@231516047@23.15.16 (190800-527344967):0) 05-03 14:34:38.425 7094 7189 E bujr : at bujr.e(:com.google.android.gms@231516047@23.15.16 (190800-527344967):4) 05-03 14:34:38.425 7094 7189 E bujr : at bujr.f(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.425 7094 7189 E bujr : at aumw.eq(:com.google.android.gms@231516047@23.15.16 (190800-527344967):1) 05-03 14:34:38.425 7094 7189 E bujr : at aund.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):12) 05-03 14:34:38.425 7094 7189 E bujr : at dcye.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.425 7094 7189 E bujr : at abqw.c(:com.google.android.gms@231516047@23.15.16 (190800-527344967):6) 05-03 14:34:38.425 7094 7189 E bujr : at abqw.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):7) 05-03 14:34:38.425 7094 7189 E bujr : at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) 05-03 14:34:38.425 7094 7189 E bujr : at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) 05-03 14:34:38.425 7094 7189 E bujr : at abwc.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):0) 05-03 14:34:38.425 7094 7189 E bujr : at java.lang.Thread.run(Thread.java:920) 05-03 14:34:38.426 7094 7189 W AsyncOperation: operation=CommitToConfigurationOperationCall, opStatusCode=29501 [CONTEXT service_id=51 ] 05-03 14:34:38.426 7094 7189 W AsyncOperation: OperationException[Status{statusCode=Stale snapshot for com.google.android.libraries.communications.conference.device#com.google.android.gm(change count changed - expected 5 but was 3), resolution=null}] 05-03 14:34:38.426 7094 7189 W AsyncOperation: at bujr.e(:com.google.android.gms@231516047@23.15.16 (190800-527344967):6) 05-03 14:34:38.426 7094 7189 W AsyncOperation: at bujr.f(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.426 7094 7189 W AsyncOperation: at aumw.eq(:com.google.android.gms@231516047@23.15.16 (190800-527344967):1) 05-03 14:34:38.426 7094 7189 W AsyncOperation: at aund.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):12) 05-03 14:34:38.426 7094 7189 W AsyncOperation: at dcye.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:38.426 7094 7189 W AsyncOperation: at abqw.c(:com.google.android.gms@231516047@23.15.16 (190800-527344967):6) 05-03 14:34:38.426 7094 7189 W AsyncOperation: at abqw.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):7) 05-03 14:34:38.426 7094 7189 W AsyncOperation: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) 05-03 14:34:38.426 7094 7189 W AsyncOperation: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) 05-03 14:34:38.426 7094 7189 W AsyncOperation: at abwc.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):0) 05-03 14:34:38.426 7094 7189 W AsyncOperation: at java.lang.Thread.run(Thread.java:920) 05-03 14:34:38.428 12366 12418 W DynamiteModule: Local module descriptor class for com.google.android.gms.crash not found. 05-03 14:34:38.448 12366 12418 I FirebaseCrashApiImpl: FirebaseCrash reporting API initialized 05-03 14:34:38.448 12366 12418 W FirebaseCrashAnalytics: Unable to log event, missing Google Analytics for Firebase library 05-03 14:34:38.451 7094 7189 I bumb : Scheduling Phenotype for a PhenotypeSyncImmediately(8, com.google.android.gm#com.google.android.gm_Hub) one off with window [1, 2] in seconds [CONTEXT service_id=51 ] 05-03 14:34:38.478 12366 12408 W xhd : Toggling Chat components off. 05-03 14:34:38.481 12366 12408 W xhd : Toggling Meet components off. 05-03 14:34:39.142 12274 12333 W DynamiteModule: Local module descriptor class for com.google.android.gms.providerinstaller.dynamite not found. 05-03 14:34:39.143 7094 9357 W ProviderHelper: Unknown dynamite feature providerinstaller.dynamite 05-03 14:34:39.144 12274 12333 W ProviderInstaller: Failed to load providerinstaller module: No acceptable module found. Local version is 0 and remote version is 0. 05-03 14:34:39.164 12274 12333 V NativeCrypto: Registering com/google/android/gms/org/conscrypt/NativeCrypto's 295 native methods... 05-03 14:34:39.174 12274 12333 I ProviderInstaller: Installed default security provider GmsCore_OpenSSL 05-03 14:34:39.980 12291 12291 I dex2oat64: dex2oat took 4.343s (7.607s cpu) (threads: 4) arena alloc=0B (0B) java alloc=18MB (19042992B) native alloc=3270KB (3348896B) free=1126KB (1153616B) 05-03 14:34:39.991 447 535 V installd: DexInv: --- END '/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk' (success) --- 05-03 14:34:40.015 521 588 V BackupManagerService: [UserID:0] restoreAtInstall pkg=com.economist.darwin token=c restoreSet=0 05-03 14:34:40.015 521 588 V BackupManagerService: [UserID:0] Finishing install immediately 05-03 14:34:40.020 447 535 E installd: Couldn't opendir /data/app/vmdl2114676648.tmp: No such file or directory 05-03 14:34:40.020 447 535 E installd: Failed to delete /data/app/vmdl2114676648.tmp: No such file or directory 05-03 14:34:40.024 521 555 D CompanionDeviceManagerService: onPackageModified(packageName = com.google.android.apps.docs) 05-03 14:34:40.026 521 624 I RoleService: Granting default roles... 05-03 14:34:40.026 521 630 I InputReader: Reconfiguring input devices, changes=KEYBOARD_LAYOUTS | 05-03 14:34:40.038 521 588 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10155; state: DISABLED 05-03 14:34:40.039 521 521 I Telecom : DefaultDialerCache: Refreshing default dialer for user 0: now com.android.dialer: DDC.oR@ATU 05-03 14:34:40.044 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.contacts 05-03 14:34:40.046 521 565 E system_server: No package ID 7f found for ID 0x7f0802ab. 05-03 14:34:40.046 521 565 E system_server: No package ID 7f found for ID 0x7f13050b. 05-03 14:34:40.046 521 565 E system_server: No package ID 7f found for ID 0x7f13050b. 05-03 14:34:40.046 521 565 E system_server: No package ID 7f found for ID 0x7f0802a7. 05-03 14:34:40.046 521 565 E system_server: No package ID 7f found for ID 0x7f130509. 05-03 14:34:40.046 521 565 E system_server: No package ID 7f found for ID 0x7f130509. 05-03 14:34:40.047 521 565 E system_server: No package ID 7f found for ID 0x7f0802a9. 05-03 14:34:40.047 521 565 E system_server: No package ID 7f found for ID 0x7f13050a. 05-03 14:34:40.047 521 565 E system_server: No package ID 7f found for ID 0x7f13050a. 05-03 14:34:40.052 1491 1491 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.economist.darwin. Reason: package android.intent.action.PACKAGE_ADDED 05-03 14:34:40.055 986 986 D CarrierSvcBindHelper: No carrier app for: 0 05-03 14:34:40.055 986 986 D ImsResolver: maybeAddedImsService, packageName: com.google.android.apps.docs 05-03 14:34:40.061 521 630 I InputReader: Reconfiguring input devices, changes=KEYBOARD_LAYOUTS | 05-03 14:34:40.061 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:40.067 2840 2840 I GsaVoiceInteractionSrv: O received Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin flg=0x4000010 (has extras) } 05-03 14:34:40.084 7382 7382 I Finsky : [2] vgr.g(0): vgr - Received: android.intent.action.PACKAGE_ADDED, [jT1dlzsIw9DR6WiJBEnpDIn8K9hGmUs8U3CLNpNyMyo] 05-03 14:34:40.096 986 986 D CarrierSvcBindHelper: No carrier app for: 0 05-03 14:34:40.096 7382 7459 I Finsky : [61] jfn.a(5): AIM: AppInfoManager-Perf > OnDeviceAppInfo > cacheHitCount=0, cacheMissCount=1. Missed in cache (limit 10) : [com.economist.darwin] 05-03 14:34:40.101 7382 7382 I Finsky : [2] zrf.a(8): Clearing split related stale data. 05-03 14:34:40.102 7382 7459 I Finsky : [61] jdd.apply(114): AIM: Got app ownership map. App counts: . Unique apps: 0 05-03 14:34:40.105 7382 12034 I Finsky : [199] kaq.adc(17): [ContentSync] finished, scheduled=false 05-03 14:34:40.106 986 986 D ImsResolver: maybeAddedImsService, packageName: com.economist.darwin 05-03 14:34:40.111 986 986 D CarrierConfigLoader: mHandler: EVENT_PACKAGE_CHANGED phoneId: 0 05-03 14:34:40.113 7382 12030 I Finsky : [196] nix.run(3): Wrote row to frosting DB: 239 05-03 14:34:40.114 7382 7382 I Finsky : [2] agcs.c(5): Do not start WearSupportService due to Wear service optimization 05-03 14:34:40.115 7382 7382 I Finsky : [2] qlc.h(1): DTU: Received onPackageAdded, replacing: false 05-03 14:34:40.118 521 3490 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin flg=0x4000010 (has extras) } to com.google.android.packageinstaller/com.android.packageinstaller.PackageInstalledReceiver 05-03 14:34:40.127 7382 12045 I Finsky : [207] at.a(9): Asset module storage cleared for package com.economist.darwin. 05-03 14:34:40.140 521 521 I Telecom : DefaultDialerCache: Refreshing default dialer for user 0: now com.android.dialer: DDC.oR@ATg 05-03 14:34:40.141 521 521 V BackupManagerConstants: getFullBackupIntervalMilliseconds(...) returns 86400000 05-03 14:34:40.141 521 521 V BackupManagerConstants: getFullBackupRequiredNetworkType(...) returns 2 05-03 14:34:40.142 521 521 V BackupManagerConstants: getFullBackupRequireCharging(...) returns true 05-03 14:34:40.147 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.packagemonitor.impl.PackageMonitorReceiverImpl$RegisteredReceiver 05-03 14:34:40.148 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.packagemonitor.backgroundimpl.BackgroundPackageMonitorReceiverImpl$RegisteredReceiver 05-03 14:34:40.148 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin flg=0x4000010 (has extras) } to com.google.android.gms/.games.chimera.GamesSystemBroadcastReceiverProxy 05-03 14:34:40.148 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin flg=0x4000010 (has extras) } to com.google.android.gms/.gass.chimera.PackageChangeBroadcastReceiver 05-03 14:34:40.148 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin flg=0x4000010 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver 05-03 14:34:40.148 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin flg=0x4000010 (has extras) } to com.google.android.googlequicksearchbox/com.google.android.apps.gsa.googlequicksearchbox.GelStubAppWatcher 05-03 14:34:40.148 8638 8654 I PermissionControllerServiceImpl: Updating user sensitive for uid 10125 05-03 14:34:40.149 8638 8654 I PermissionControllerServiceImpl: Updating user sensitive for uid 10155 05-03 14:34:40.149 521 566 I ActivityManager: com.google.android.packageinstaller is exempt from freezer 05-03 14:34:40.150 521 566 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10066; state: DISABLED 05-03 14:34:40.150 521 566 D CompatibilityChangeReporter: Compat change id reported: 177438394; UID 10066; state: DISABLED 05-03 14:34:40.150 521 566 D CompatibilityChangeReporter: Compat change id reported: 135772972; UID 10066; state: DISABLED 05-03 14:34:40.150 521 566 D CompatibilityChangeReporter: Compat change id reported: 135754954; UID 10066; state: ENABLED 05-03 14:34:40.151 521 567 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10066; state: ENABLED 05-03 14:34:40.154 7094 12106 I Fitness : OnPackageChangedOperation got intent: Intent { act=android.intent.action.PACKAGE_CHANGED dat=package:com.google.android.apps.docs flg=0x45000010 pkg=com.google.android.gms cmp=com.google.android.gms/.chimera.PersistentIntentOperationService (has extras) } [CONTEXT service_id=17 ] 05-03 14:34:40.156 340 340 D Zygote : Forked child process 12461 05-03 14:34:40.182 12461 12461 W ackageinstalle: Unexpected CPU variant for X86 using defaults: x86_64 05-03 14:34:40.189 12231 12452 I ProvidersCache: Provider returned no roots. Possibly naughty: com.google.android.apps.docs.storage 05-03 14:34:40.189 12461 12461 E ackageinstalle: Not starting debugger since process cannot load the jdwp agent. 05-03 14:34:40.211 7382 7460 I Finsky : [62] jfj.a(11): AIM: AppInfoManager-Perf > ItemModel > CacheSize=0, cacheHitCount=0, cacheMissCount=1, total appsWithNoServerDataCount=0. Missed in cache (limit 10) : [com.economist.darwin] 05-03 14:34:40.213 521 567 W ActivityManager: Slow operation: 64ms so far, now at startProcess: done updating battery stats 05-03 14:34:40.213 521 567 W ActivityManager: Slow operation: 64ms so far, now at startProcess: building log message 05-03 14:34:40.213 7382 7460 I Finsky : [62] jfm.n(3): AIM: AppInfoManager-Perf > ItemModel > ItemStore Called 1 apps cacheOptions = USE_CACHE 05-03 14:34:40.213 521 567 I ActivityManager: Start proc 12461:com.google.android.packageinstaller/u0a66 for broadcast {com.google.android.packageinstaller/com.android.packageinstaller.PackageInstalledReceiver} 05-03 14:34:40.213 521 567 W ActivityManager: Slow operation: 64ms so far, now at startProcess: starting to update pids map 05-03 14:34:40.213 521 567 W ActivityManager: Slow operation: 64ms so far, now at startProcess: done updating pids map 05-03 14:34:40.213 7382 7460 I Finsky : [62] jfj.a(96): AIM: ItemModel fetch - unauth(1), auth() 05-03 14:34:40.237 12461 12461 D CompatibilityChangeReporter: Compat change id reported: 171979766; UID 10066; state: ENABLED 05-03 14:34:40.241 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:40.243 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:40.261 7382 12030 I Finsky : [196] nix.run(3): Wrote row to frosting DB: 240 05-03 14:34:40.275 7094 12105 I Fitness : FitCleanupIntentOperation received Intent android.intent.action.PACKAGE_CHANGED [CONTEXT service_id=17 ] 05-03 14:34:40.294 1035 1727 D EGL_emulation: app_time_stats: avg=2262.14ms min=12.18ms max=6243.08ms count=3 05-03 14:34:40.307 7094 12106 W GCM : Unexpected forwarded intent: Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin flg=0x4000010 pkg=com.google.android.gms cmp=com.google.android.gms/.chimera.PersistentIntentOperationService (has extras) } 05-03 14:34:40.308 521 585 E VerityUtils: Failed to measure fs-verity, errno 1: /data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk 05-03 14:34:40.309 7094 12106 W GCM : Unexpected forwarded intent: Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin flg=0x4000010 pkg=com.google.android.gms cmp=com.google.android.gms/.chimera.PersistentIntentOperationService (has extras) } 05-03 14:34:40.346 521 588 I PackageManager: Integrity check passed for file:///data/app/vmdl303394930.tmp 05-03 14:34:40.359 521 565 W PackageManager: Failed registering loading progress callback. Package is fully loaded. 05-03 14:34:40.364 7094 8273 W ChimeraUtils: Module com.google.android.gms.nearby_en missing resource null(0) 05-03 14:34:40.396 12461 12461 V GraphicsEnvironment: ANGLE Developer option for 'com.google.android.packageinstaller' set to: 'default' 05-03 14:34:40.396 12461 12461 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported. 05-03 14:34:40.398 12064 12225 I Auth : [SupervisedAccountIntentOperation] onHandleIntent(): android.intent.action.PACKAGE_ADDED 05-03 14:34:40.400 12064 12480 I BlockstoreStorage: Clearing Blockstore Data for package com.economist.darwin [CONTEXT service_id=258 ] 05-03 14:34:40.402 12064 12480 I BlockstoreStorage: Keyless data not found or its IsLastInstallationData = false. [CONTEXT service_id=258 ] 05-03 14:34:40.406 7094 8273 W ChimeraUtils: Module com.google.android.gms.nearby_en missing resource null(0) 05-03 14:34:40.416 12461 12461 D NetworkSecurityConfig: No Network Security Config specified, using platform default 05-03 14:34:40.417 12461 12461 D NetworkSecurityConfig: No Network Security Config specified, using platform default 05-03 14:34:40.420 12064 12064 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:40.420 12064 12064 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:40.432 12064 12064 D BoundBrokerSvc: onUnbind: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:40.457 521 530 I system_server: Background concurrent copying GC freed 370467(17MB) AllocSpace objects, 88(4528KB) LOS objects, 49% free, 19MB/39MB, paused 638us,108us total 437.439ms 05-03 14:34:40.466 7382 7396 I android.vendin: Background concurrent copying GC freed 235520(8854KB) AllocSpace objects, 24(1184KB) LOS objects, 49% free, 11MB/22MB, paused 571us,109us total 367.398ms 05-03 14:34:40.466 7382 7396 W android.vendin: Reducing the number of considered missed Gc histogram windows from 298 to 100 05-03 14:34:40.475 521 532 W System : A resource failed to call close. 05-03 14:34:40.478 521 532 W System : A resource failed to call close. 05-03 14:34:40.510 7094 8273 W ChimeraUtils: Module com.google.android.gms.nearby_en missing resource null(0) 05-03 14:34:40.515 521 588 V BackupManagerService: [UserID:0] restoreAtInstall pkg=com.economist.darwin.test token=d restoreSet=0 05-03 14:34:40.516 521 588 V BackupManagerService: [UserID:0] Finishing install immediately 05-03 14:34:40.517 447 535 E installd: Couldn't opendir /data/app/vmdl303394930.tmp: No such file or directory 05-03 14:34:40.517 447 535 E installd: Failed to delete /data/app/vmdl303394930.tmp: No such file or directory 05-03 14:34:40.528 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:40.543 12064 12480 I Dck : doesDeviceHaveSupportedWirelessCapabilities: wccSysProp:0, wccMendel:1, deviceWccOverride:false [CONTEXT service_id=289 ] 05-03 14:34:40.545 12064 12480 I Dck : isDckModuleEligible: isAtLeastR:true, hasWccSupport:false, downloadAllowed:false [CONTEXT service_id=289 ] 05-03 14:34:40.545 12064 12480 W Dck : Dck module not eligible for asynchronous downloading [CONTEXT service_id=289 ] 05-03 14:34:40.556 521 588 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10156; state: DISABLED 05-03 14:34:40.561 1491 1491 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.economist.darwin.test. Reason: package android.intent.action.PACKAGE_ADDED 05-03 14:34:40.581 8638 10410 I PermissionControllerServiceImpl: Updating user sensitive for uid 10156 05-03 14:34:40.585 521 630 I InputReader: Reconfiguring input devices, changes=KEYBOARD_LAYOUTS | 05-03 14:34:40.587 2840 2840 I GsaVoiceInteractionSrv: O received Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } 05-03 14:34:40.587 7382 7382 I Finsky : [2] vgr.g(0): vgr - Received: android.intent.action.PACKAGE_ADDED, [po1Su8iV47FnK8_AdNTaMWnCBz_mL7KdoDwh7nTB-UU] 05-03 14:34:40.613 521 603 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.google.android.packageinstaller/com.android.packageinstaller.PackageInstalledReceiver 05-03 14:34:40.620 7382 7444 I Finsky : [58] jfn.a(5): AIM: AppInfoManager-Perf > OnDeviceAppInfo > cacheHitCount=0, cacheMissCount=1. Missed in cache (limit 10) : [com.economist.darwin.test] 05-03 14:34:40.620 7382 7444 I Finsky : [58] jdd.apply(114): AIM: Got app ownership map. App counts: . Unique apps: 0 05-03 14:34:40.625 7382 7382 I Finsky : [2] zrf.a(8): Clearing split related stale data. 05-03 14:34:40.631 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.packagemonitor.impl.PackageMonitorReceiverImpl$RegisteredReceiver 05-03 14:34:40.631 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.android.vending/com.google.android.finsky.packagemonitor.backgroundimpl.BackgroundPackageMonitorReceiverImpl$RegisteredReceiver 05-03 14:34:40.631 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.google.android.gms/.games.chimera.GamesSystemBroadcastReceiverProxy 05-03 14:34:40.631 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.google.android.gms/.gass.chimera.PackageChangeBroadcastReceiver 05-03 14:34:40.632 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver 05-03 14:34:40.632 521 566 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin.test flg=0x4000010 (has extras) } to com.google.android.googlequicksearchbox/com.google.android.apps.gsa.googlequicksearchbox.GelStubAppWatcher 05-03 14:34:40.634 7382 7382 I Finsky : [2] agcs.c(5): Do not start WearSupportService due to Wear service optimization 05-03 14:34:40.635 7382 7382 I Finsky : [2] qlc.h(1): DTU: Received onPackageAdded, replacing: false 05-03 14:34:40.637 7382 12045 I Finsky : [207] at.a(9): Asset module storage cleared for package com.economist.darwin.test. 05-03 14:34:40.637 7382 12034 I Finsky : [199] kaq.adc(17): [ContentSync] finished, scheduled=false 05-03 14:34:40.639 12231 12491 I ProvidersCache: Provider returned no roots. Possibly naughty: com.google.android.apps.docs.storage 05-03 14:34:40.649 521 566 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10066; state: DISABLED 05-03 14:34:40.654 986 986 D CarrierSvcBindHelper: No carrier app for: 0 05-03 14:34:40.661 986 986 D ImsResolver: maybeAddedImsService, packageName: com.economist.darwin.test 05-03 14:34:40.662 986 986 D CarrierConfigLoader: mHandler: EVENT_PACKAGE_CHANGED phoneId: 0 05-03 14:34:40.668 521 870 I ActivityManager: Force stopping com.economist.darwin appid=10155 user=0: from pid 12486 05-03 14:34:40.669 521 521 I Telecom : DefaultDialerCache: Refreshing default dialer for user 0: now com.android.dialer: DDC.oR@ATs 05-03 14:34:40.681 521 521 V BackupManagerConstants: getFullBackupIntervalMilliseconds(...) returns 86400000 05-03 14:34:40.681 521 521 V BackupManagerConstants: getFullBackupRequiredNetworkType(...) returns 2 05-03 14:34:40.681 521 521 V BackupManagerConstants: getFullBackupRequireCharging(...) returns true 05-03 14:34:40.681 521 565 W PackageManager: Failed registering loading progress callback. Package is fully loaded. 05-03 14:34:40.724 7382 12030 I Finsky : [196] nix.run(3): Wrote row to frosting DB: 241 05-03 14:34:40.727 12064 12203 I Icing : Indexing com.google.android.gms-apps from com.google.android.gms 05-03 14:34:40.737 7382 12030 I Finsky : [196] nix.run(3): Wrote row to frosting DB: 242 05-03 14:34:40.740 7382 7459 I Finsky : [61] jfj.a(11): AIM: AppInfoManager-Perf > ItemModel > CacheSize=0, cacheHitCount=0, cacheMissCount=1, total appsWithNoServerDataCount=0. Missed in cache (limit 10) : [com.economist.darwin.test] 05-03 14:34:40.740 7382 7459 I Finsky : [61] jfm.n(3): AIM: AppInfoManager-Perf > ItemModel > ItemStore Called 1 apps cacheOptions = USE_CACHE 05-03 14:34:40.741 7382 7459 I Finsky : [61] jfj.a(96): AIM: ItemModel fetch - unauth(1), auth() 05-03 14:34:40.744 7382 7447 I Finsky : [59] rjm.apply(5): ItemStore: Not cached: com.economist.darwin.test 05-03 14:34:40.764 7094 8023 W ChimeraUtils: Module com.google.android.gms.nearby_en missing resource null(0) 05-03 14:34:40.773 986 986 D CarrierSvcBindHelper: No carrier app for: 0 05-03 14:34:40.778 7094 8023 W ChimeraUtils: Module com.google.android.gms.nearby_en missing resource null(0) 05-03 14:34:40.782 7094 8023 W ChimeraUtils: Module com.google.android.gms.nearby_en missing resource null(0) 05-03 14:34:40.784 7094 12070 W GCM : Unexpected forwarded intent: Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin.test flg=0x4000010 pkg=com.google.android.gms cmp=com.google.android.gms/.chimera.PersistentIntentOperationService (has extras) } 05-03 14:34:40.787 7094 12105 W GCM : Unexpected forwarded intent: Intent { act=android.intent.action.PACKAGE_ADDED dat=package:com.economist.darwin.test flg=0x4000010 pkg=com.google.android.gms cmp=com.google.android.gms/.chimera.PersistentIntentOperationService (has extras) } 05-03 14:34:40.791 7094 12106 I FontLog : Package com.economist.darwin.test has no metadata [CONTEXT service_id=132 ] 05-03 14:34:40.818 12064 12183 I Auth : [SupervisedAccountIntentOperation] onHandleIntent(): android.intent.action.PACKAGE_ADDED 05-03 14:34:40.823 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:40.825 12064 12183 I BlockstoreStorage: Clearing Blockstore Data for package com.economist.darwin.test [CONTEXT service_id=258 ] 05-03 14:34:40.827 12064 12183 I BlockstoreStorage: Keyless data not found or its IsLastInstallationData = false. [CONTEXT service_id=258 ] 05-03 14:34:40.831 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:40.853 12064 12225 I Dck : doesDeviceHaveSupportedWirelessCapabilities: wccSysProp:0, wccMendel:1, deviceWccOverride:false [CONTEXT service_id=289 ] 05-03 14:34:40.854 12064 12225 I Dck : isDckModuleEligible: isAtLeastR:true, hasWccSupport:false, downloadAllowed:false [CONTEXT service_id=289 ] 05-03 14:34:40.854 12064 12225 W Dck : Dck module not eligible for asynchronous downloading [CONTEXT service_id=289 ] 05-03 14:34:40.859 12064 12064 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:40.860 12064 12064 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:40.900 521 530 I system_server: Background concurrent copying GC freed 88938(4336KB) AllocSpace objects, 11(284KB) LOS objects, 49% free, 19MB/39MB, paused 1.034ms,113us total 382.756ms 05-03 14:34:40.918 12064 12203 I Icing : Indexing done com.google.android.gms-apps 05-03 14:34:40.921 12064 12203 I Icing : Indexing com.google.android.gms-apps from com.google.android.gms 05-03 14:34:40.923 12064 12203 I Icing : Indexing done com.google.android.gms-apps 05-03 14:34:40.932 7094 7189 W ChimeraUtils: Module com.google.android.gms.nearby_en missing resource null(0) 05-03 14:34:40.945 521 585 E VerityUtils: Failed to measure fs-verity, errno 1: /data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk 05-03 14:34:40.945 7094 7189 W ChimeraUtils: Module com.google.android.gms.nearby_en missing resource null(0) 05-03 14:34:40.951 7094 7189 W ChimeraUtils: Module com.google.android.gms.nearby_en missing resource null(0) 05-03 14:34:40.961 12064 12064 D BoundBrokerSvc: onUnbind: Intent { act=com.google.android.gms.common.BIND_SHARED_PREFS pkg=com.google.android.gms } 05-03 14:34:40.972 12064 12203 I Icing : Indexing com.google.android.gms-apps from com.google.android.gms 05-03 14:34:40.973 12064 12203 I Icing : Indexing done com.google.android.gms-apps 05-03 14:34:41.025 521 624 I RoleService: Granting default roles... 05-03 14:34:41.038 7382 7447 E Finsky : [59] rju.a(16): ItemStore: getItems RPC failed. 05-03 14:34:41.038 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.contacts 05-03 14:34:41.041 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:41.123 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:41.126 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:41.295 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:41.395 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:41.401 8638 12040 W Role : Cannot get ApplicationInfo for default holder: com.google.android.as 05-03 14:34:42.064 521 835 I ActivityManager: Force stopping com.economist.darwin appid=10155 user=0: from pid 12507 05-03 14:34:42.073 986 986 D CarrierSvcBindHelper: No carrier app for: 0 05-03 14:34:42.294 12518 12518 D AndroidRuntime: >>>>>> START com.android.internal.os.RuntimeInit uid 2000 <<<<<< 05-03 14:34:42.298 12518 12518 I AndroidRuntime: Using default boot image 05-03 14:34:42.298 12518 12518 I AndroidRuntime: Leaving lock profiling enabled 05-03 14:34:42.298 12518 12518 W libc : Access denied finding property "odsign.verification.success" 05-03 14:34:42.301 12518 12518 W app_process: ART APEX data files are untrusted. 05-03 14:34:42.294 12518 12518 W app_process: type=1400 audit(0.0:103): avc: denied { read } for name="u:object_r:odsign_prop:s0" dev="tmpfs" ino=203 scontext=u:r:shell:s0 tcontext=u:object_r:odsign_prop:s0 tclass=file permissive=0 05-03 14:34:42.374 12518 12518 D app_process: Time zone APEX ICU file found: /apex/com.android.tzdata/etc/icu/icu_tzdata.dat 05-03 14:34:42.374 12518 12518 D app_process: I18n APEX ICU file found: /apex/com.android.i18n/etc/icu/icudt68l.dat 05-03 14:34:42.382 12518 12518 W app_process: Unexpected CPU variant for X86 using defaults: x86_64 05-03 14:34:42.414 12518 12518 D AndroidRuntime: Calling main entry com.android.commands.am.Am 05-03 14:34:42.420 521 835 I ActivityManager: Force stopping com.economist.darwin appid=10155 user=0: start instr 05-03 14:34:42.424 521 835 D CompatibilityChangeReporter: Compat change id reported: 135634846; UID 10155; state: DISABLED 05-03 14:34:42.425 521 567 D CompatibilityChangeReporter: Compat change id reported: 143937733; UID 10155; state: ENABLED 05-03 14:34:42.428 340 340 D Zygote : Forked child process 12533 05-03 14:34:42.429 521 567 I ActivityManager: Start proc 12533:com.economist.darwin/u0a155 for added application com.economist.darwin 05-03 14:34:42.435 12533 12533 W conomist.darwi: Unexpected CPU variant for X86 using defaults: x86_64 05-03 14:34:42.441 12533 12533 E conomist.darwi: Not starting debugger since process cannot load the jdwp agent. 05-03 14:34:42.455 12533 12533 D CompatibilityChangeReporter: Compat change id reported: 171979766; UID 10155; state: ENABLED 05-03 14:34:42.459 12533 12533 W ActivityThread: Package uses different ABI(s) than its instrumentation: package[com.economist.darwin]: x86_64, null instrumentation[com.economist.darwin.test]: null, null 05-03 14:34:42.471 12533 12533 W conomist.darwi: ClassLoaderContext classpath size mismatch. expected=0, found=1 (PCL[] | PCL[/system/framework/android.test.runner.jar*1585996318]) 05-03 14:34:42.471 12533 12533 W conomist.darwi: ClassLoaderContext classpath size mismatch. expected=0, found=2 (PCL[] | PCL[/system/framework/android.test.runner.jar*1585996318:/system/framework/android.test.mock.jar*2265025959]) 05-03 14:34:42.493 12533 12533 W conomist.darwi: ClassLoaderContext classpath size mismatch. expected=0, found=4 (PCL[] | PCL[/system/framework/android.test.runner.jar*1585996318:/system/framework/android.test.mock.jar*2265025959:/system/framework/android.test.base.jar*3790657674:/data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk*1642101620]) 05-03 14:34:42.499 12533 12533 V GraphicsEnvironment: ANGLE Developer option for 'com.economist.darwin' set to: 'default' 05-03 14:34:42.499 12533 12533 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported. 05-03 14:34:42.502 12533 12533 D ApplicationLoaders: Returning zygote-cached class loader: /system/framework/android.test.base.jar 05-03 14:34:42.503 12533 12533 D ApplicationLoaders: Returning zygote-cached class loader: /system/framework/android.test.base.jar 05-03 14:34:42.504 12533 12533 W conomist.darwi: ClassLoaderContext shared library size mismatch. Expected=0, found=2 (PCL[] | PCL[]{PCL[/system/framework/android.test.base.jar*3790657674]#PCL[/system/framework/android.test.mock.jar*2265025959]}) 05-03 14:34:42.505 12533 12533 W conomist.darwi: ClassLoaderContext shared library size mismatch. Expected=0, found=3 (PCL[] | PCL[]{PCL[/system/framework/android.test.base.jar*3790657674]#PCL[/system/framework/android.test.mock.jar*2265025959]#PCL[/system/framework/android.test.runner.jar*1585996318]{PCL[/system/framework/android.test.base.jar*3790657674]#PCL[/system/framework/android.test.mock.jar*2265025959]}};PCL[/system/framework/android.test.runner.jar*1585996318:/system/framework/android.test.mock.jar*2265025959:/system/framework/android.test.base.jar*3790657674:/data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk*1642101620:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk*2940312677:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk!classes2.dex*2727372769:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk!classes3.dex*2911050639]) 05-03 14:34:42.507 12533 12533 W conomist.darwi: ClassLoaderContext classpath size mismatch. expected=0, found=1 (PCL[] | PCL[/system/framework/android.test.runner.jar*1585996318]{PCL[/system/framework/android.test.base.jar*3790657674]#PCL[/system/framework/android.test.mock.jar*2265025959]#PCL[/system/framework/android.test.runner.jar*1585996318]{PCL[/system/framework/android.test.base.jar*3790657674]#PCL[/system/framework/android.test.mock.jar*2265025959]}};PCL[/system/framework/android.test.runner.jar*1585996318:/system/framework/android.test.mock.jar*2265025959:/system/framework/android.test.base.jar*3790657674:/data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk*1642101620:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk*2940312677:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk!classes2.dex*2727372769:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk!classes3.dex*2911050639]) 05-03 14:34:42.507 12533 12533 W conomist.darwi: ClassLoaderContext classpath size mismatch. expected=0, found=2 (PCL[] | PCL[/system/framework/android.test.runner.jar*1585996318:/system/framework/android.test.mock.jar*2265025959]{PCL[/system/framework/android.test.base.jar*3790657674]#PCL[/system/framework/android.test.mock.jar*2265025959]#PCL[/system/framework/android.test.runner.jar*1585996318]{PCL[/system/framework/android.test.base.jar*3790657674]#PCL[/system/framework/android.test.mock.jar*2265025959]}};PCL[/system/framework/android.test.runner.jar*1585996318:/system/framework/android.test.mock.jar*2265025959:/system/framework/android.test.base.jar*3790657674:/data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk*1642101620:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk*2940312677:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk!classes2.dex*2727372769:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk!classes3.dex*2911050639]) 05-03 14:34:42.528 12533 12533 W conomist.darwi: ClassLoaderContext classpath size mismatch. expected=0, found=4 (PCL[] | PCL[/system/framework/android.test.runner.jar*1585996318:/system/framework/android.test.mock.jar*2265025959:/system/framework/android.test.base.jar*3790657674:/data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk*1642101620]{PCL[/system/framework/android.test.base.jar*3790657674]#PCL[/system/framework/android.test.mock.jar*2265025959]#PCL[/system/framework/android.test.runner.jar*1585996318]{PCL[/system/framework/android.test.base.jar*3790657674]#PCL[/system/framework/android.test.mock.jar*2265025959]}};PCL[/system/framework/android.test.runner.jar*1585996318:/system/framework/android.test.mock.jar*2265025959:/system/framework/android.test.base.jar*3790657674:/data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk*1642101620:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk*2940312677:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk!classes2.dex*2727372769:/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk!classes3.dex*2911050639]) 05-03 14:34:42.530 12533 12533 D AndroidRuntime: Shutting down VM 05-03 14:34:42.531 12533 12533 E AndroidRuntime: FATAL EXCEPTION: main 05-03 14:34:42.531 12533 12533 E AndroidRuntime: Process: com.economist.darwin, PID: 12533 05-03 14:34:42.531 12533 12533 E AndroidRuntime: java.lang.RuntimeException: Unable to instantiate instrumentation ComponentInfo{com.economist.darwin.test/androidx.test.runner.AndroidJUnitRunner}: java.lang.ClassNotFoundException: Didn't find class "androidx.test.runner.AndroidJUnitRunner" on path: DexPathList[[zip file "/system/framework/android.test.runner.jar", zip file "/system/framework/android.test.mock.jar", zip file "/system/framework/android.test.base.jar", zip file "/data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk", zip file "/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk"],nativeLibraryDirectories=[/system/lib64, /system_ext/lib64]] 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at android.app.ActivityThread.initInstrumentation(ActivityThread.java:6879) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at android.app.ActivityThread.handleBindApplication(ActivityThread.java:6663) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at android.app.ActivityThread.access$1500(ActivityThread.java:247) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2053) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at android.os.Handler.dispatchMessage(Handler.java:106) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at android.os.Looper.loopOnce(Looper.java:201) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at android.os.Looper.loop(Looper.java:288) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at android.app.ActivityThread.main(ActivityThread.java:7839) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at java.lang.reflect.Method.invoke(Native Method) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:548) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:1003) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: Caused by: java.lang.ClassNotFoundException: Didn't find class "androidx.test.runner.AndroidJUnitRunner" on path: DexPathList[[zip file "/system/framework/android.test.runner.jar", zip file "/system/framework/android.test.mock.jar", zip file "/system/framework/android.test.base.jar", zip file "/data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk", zip file "/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk"],nativeLibraryDirectories=[/system/lib64, /system_ext/lib64]] 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at dalvik.system.BaseDexClassLoader.findClass(BaseDexClassLoader.java:218) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at java.lang.ClassLoader.loadClass(ClassLoader.java:379) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at java.lang.ClassLoader.loadClass(ClassLoader.java:312) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: at android.app.ActivityThread.initInstrumentation(ActivityThread.java:6875) 05-03 14:34:42.531 12533 12533 E AndroidRuntime: ... 10 more 05-03 14:34:42.533 521 12549 I DropBoxManagerService: add tag=data_app_crash isTagEnabled=true flags=0x2 05-03 14:34:42.534 12533 12533 I Process : Sending signal. PID: 12533 SIG: 9 05-03 14:34:42.535 521 566 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 05-03 14:34:42.535 521 566 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 05-03 14:34:42.542 340 340 I Zygote : Process 12533 exited due to signal 9 (Killed) 05-03 14:34:42.543 521 835 I ActivityManager: Process com.economist.darwin (pid 12533) has died: fg FGS 05-03 14:34:42.543 521 568 I libprocessgroup: Successfully killed process cgroup uid 10155 pid 12533 in 0ms 05-03 14:34:42.546 521 835 W ActivityManager: Crash of app com.economist.darwin running instrumentation ComponentInfo{com.economist.darwin.test/androidx.test.runner.AndroidJUnitRunner} 05-03 14:34:42.546 521 835 I ActivityManager: Force stopping com.economist.darwin appid=10155 user=0: finished inst 05-03 14:34:42.551 12518 12518 I app_process: System.exit called, status: 0 05-03 14:34:42.551 12518 12518 I AndroidRuntime: VM exiting with result code 0. 05-03 14:34:43.236 521 521 D CompatibilityChangeReporter: Compat change id reported: 168419799; UID 10121; state: DISABLED 05-03 14:34:43.251 521 763 D CompatibilityChangeReporter: Compat change id reported: 149924527; UID 10121; state: ENABLED 05-03 14:34:43.252 521 763 D CompatibilityChangeReporter: Compat change id reported: 132649864; UID 10121; state: DISABLED 05-03 14:34:43.331 12366 12560 W DynamiteModule: Local module descriptor class for com.google.android.gms.providerinstaller.dynamite not found. 05-03 14:34:43.334 7094 7112 W ProviderHelper: Unknown dynamite feature providerinstaller.dynamite 05-03 14:34:43.338 12366 12560 W ProviderInstaller: Failed to load providerinstaller module: No acceptable module found. Local version is 0 and remote version is 0. 05-03 14:34:43.356 12366 12560 V NativeCrypto: Registering com/google/android/gms/org/conscrypt/NativeCrypto's 295 native methods... 05-03 14:34:43.367 12366 12560 I ProviderInstaller: Installed default security provider GmsCore_OpenSSL 05-03 14:34:43.421 1599 1621 W ocess.gservice: Reducing the number of considered missed Gc histogram windows from 189 to 100 05-03 14:34:43.530 7094 12252 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.phenotype.service.sync.PhenotypeConfigurator started execution. cause:4 exec_start_elapsed_seconds: 3944 [CONTEXT service_id=218 ] 05-03 14:34:43.531 7094 7094 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.phenotype.service.START dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService } 05-03 14:34:43.531 7094 7094 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.phenotype.service.START dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService } 05-03 14:34:43.665 521 763 D CompatibilityChangeReporter: Compat change id reported: 182734110; UID 10121; state: DISABLED 05-03 14:34:43.678 1491 1500 W FuseDaemon: Reducing the number of considered missed Gc histogram windows from 302 to 100 05-03 14:34:43.694 1491 1818 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:43.719 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Calm/Calm.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:43.736 1491 1991 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:43.759 7094 12254 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.ipa.base.IpaGcmTaskService started execution. cause:9 exec_start_elapsed_seconds: 3944 [CONTEXT service_id=218 ] 05-03 14:34:43.783 1491 5300 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:43.791 7094 12252 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.ipa.base.IpaGcmTaskService finished executing. cause:9 result: 1 elapsed_millis: 45 uptime_millis: 45 exec_start_elapsed_seconds: 3944 [CONTEXT service_id=218 ] 05-03 14:34:43.847 1491 1818 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:43.872 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Calm/Calm.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:43.876 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Music_box/Music Box.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:43.892 1491 1818 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:43.927 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Music_box/Music Box.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:43.928 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Nudge/Nudge.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:43.932 1491 1799 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:43.975 1491 1991 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:43.982 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Nudge/Nudge.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:43.983 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Piggyback/Piggyback.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.017 1491 1818 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:44.021 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Piggyback/Piggyback.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.022 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Shrink_ray/Shrink Ray.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.028 205 12024 I keystore2: keystore2::watchdog: Watchdog thread idle -> terminating. Have a great day. 05-03 14:34:44.048 1491 1818 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:44.063 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Shrink_ray/Shrink Ray.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.064 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Snap/Snappy.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.088 1491 1818 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:44.108 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Snap/Snappy.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.111 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Sweet/Sweet.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.139 1491 1991 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:44.154 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Sweet/Sweet.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.156 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Tennis/Tennis.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.181 1491 1991 W MediaProvider: isAppCloneUserPair for user 0: false 05-03 14:34:44.185 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Tennis/Tennis.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.188 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Treasure/Treasure.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.246 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Treasure/Treasure.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.248 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Welcome/Welcome.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.291 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Welcome/Welcome.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.294 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Whistle/Whistle.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.327 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Whistle/Whistle.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.328 1491 3738 I MediaProvider: Begin Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Tones/Tones.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.355 1491 3738 I MediaProvider: End Intent { act=android.intent.action.MEDIA_SCANNER_SCAN_FILE dat=file:///storage/emulated/0/Android/media/com.google.android.gm/Notifications/Tones/Tones.ogg flg=0x1000010 cmp=com.google.android.providers.media.module/com.android.providers.media.MediaService } 05-03 14:34:44.362 7094 11915 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.ipa.base.IpaGcmTaskService started execution. cause:9 exec_start_elapsed_seconds: 3945 [CONTEXT service_id=218 ] 05-03 14:34:44.380 7094 12252 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.ipa.base.IpaGcmTaskService finished executing. cause:9 result: 1 elapsed_millis: 33 uptime_millis: 33 exec_start_elapsed_seconds: 3945 [CONTEXT service_id=218 ] 05-03 14:34:44.712 7094 7189 E bujr : Phenotype API error. Event # ealm@427c70d1, EventCode: 5 [CONTEXT service_id=51 ] 05-03 14:34:44.712 7094 7189 E bujr : buio: 29501: Stale snapshot for com.google.android.libraries.performance.primes#com.google.android.gm(change count changed - expected 7 but was 4) 05-03 14:34:44.712 7094 7189 E bujr : at bujw.b(:com.google.android.gms@231516047@23.15.16 (190800-527344967):16) 05-03 14:34:44.712 7094 7189 E bujr : at buju.g(:com.google.android.gms@231516047@23.15.16 (190800-527344967):0) 05-03 14:34:44.712 7094 7189 E bujr : at bujr.e(:com.google.android.gms@231516047@23.15.16 (190800-527344967):4) 05-03 14:34:44.712 7094 7189 E bujr : at bujr.f(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:44.712 7094 7189 E bujr : at aumw.eq(:com.google.android.gms@231516047@23.15.16 (190800-527344967):1) 05-03 14:34:44.712 7094 7189 E bujr : at aund.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):12) 05-03 14:34:44.712 7094 7189 E bujr : at dcye.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:44.712 7094 7189 E bujr : at abqw.c(:com.google.android.gms@231516047@23.15.16 (190800-527344967):6) 05-03 14:34:44.712 7094 7189 E bujr : at abqw.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):7) 05-03 14:34:44.712 7094 7189 E bujr : at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) 05-03 14:34:44.712 7094 7189 E bujr : at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) 05-03 14:34:44.712 7094 7189 E bujr : at abwc.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):0) 05-03 14:34:44.712 7094 7189 E bujr : at java.lang.Thread.run(Thread.java:920) 05-03 14:34:44.712 7094 7189 W AsyncOperation: operation=CommitToConfigurationOperationCall, opStatusCode=29501 [CONTEXT service_id=51 ] 05-03 14:34:44.712 7094 7189 W AsyncOperation: OperationException[Status{statusCode=Stale snapshot for com.google.android.libraries.performance.primes#com.google.android.gm(change count changed - expected 7 but was 4), resolution=null}] 05-03 14:34:44.712 7094 7189 W AsyncOperation: at bujr.e(:com.google.android.gms@231516047@23.15.16 (190800-527344967):6) 05-03 14:34:44.712 7094 7189 W AsyncOperation: at bujr.f(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:44.712 7094 7189 W AsyncOperation: at aumw.eq(:com.google.android.gms@231516047@23.15.16 (190800-527344967):1) 05-03 14:34:44.712 7094 7189 W AsyncOperation: at aund.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):12) 05-03 14:34:44.712 7094 7189 W AsyncOperation: at dcye.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):2) 05-03 14:34:44.712 7094 7189 W AsyncOperation: at abqw.c(:com.google.android.gms@231516047@23.15.16 (190800-527344967):6) 05-03 14:34:44.712 7094 7189 W AsyncOperation: at abqw.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):7) 05-03 14:34:44.712 7094 7189 W AsyncOperation: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) 05-03 14:34:44.712 7094 7189 W AsyncOperation: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) 05-03 14:34:44.712 7094 7189 W AsyncOperation: at abwc.run(:com.google.android.gms@231516047@23.15.16 (190800-527344967):0) 05-03 14:34:44.712 7094 7189 W AsyncOperation: at java.lang.Thread.run(Thread.java:920) 05-03 14:34:44.715 7094 11986 I bumo : Removed 0 invalid users [CONTEXT service_id=51 ] 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: Failed to commit due to stale snapshot for com.google.android.libraries.performance.primes#com.google.android.gm, triggering flag update. 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: aaoq: 29501: 29501: Stale snapshot for com.google.android.libraries.performance.primes#com.google.android.gm(change count changed - expected 7 but was 4) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at yzu.a(PG:51) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at awob.c(PG:2) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at awod.run(PG:10) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at awqc.execute(Unknown Source:0) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at awor.m(PG:1) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at awor.i(PG:8) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at awor.setException(Unknown Source:17) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at com.google.common.util.concurrent.SettableFuture.setException(Unknown Source:0) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at wgz.a(PG:4) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at pfi.run(PG:1) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at awqc.execute(Unknown Source:0) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at pfj.a(PG:2) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at pfx.b(PG:5) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at pga.p(PG:4) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at pfy.a(Unknown Source:2) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at orq.F(PG:2) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at orq.E(Unknown Source:1) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at pbs.b(Unknown Source:2) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at pbx.fZ(PG:12) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at gui.onTransact(PG:4) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at android.os.Binder.execTransactInternal(Binder.java:1184) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: at android.os.Binder.execTransact(Binder.java:1143) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: Caused by: okp: 29501: Stale snapshot for com.google.android.libraries.performance.primes#com.google.android.gm(change count changed - expected 7 but was 4) 05-03 14:34:44.716 12366 12406 W ProtoDataStoreFlagStore: ... 7 more 05-03 14:34:46.266 12231 12231 D CompatibilityChangeReporter: Compat change id reported: 150939131; UID 10063; state: ENABLED 05-03 14:34:46.286 12231 12596 I ProvidersCache: Provider returned no roots. Possibly naughty: com.google.android.apps.docs.storage 05-03 14:34:46.786 7094 11986 I bumb : Scheduling adaptive one off task with window [14400, 604800] in seconds [CONTEXT service_id=51 ] 05-03 14:34:46.833 7094 12254 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.phenotype.service.sync.PhenotypeConfigurator finished executing. cause:4 result: 1 elapsed_millis: 3314 uptime_millis: 3314 exec_start_elapsed_seconds: 3944 [CONTEXT service_id=218 ] 05-03 14:34:49.652 12366 12617 I FirebaseCrash: Sending crashes 05-03 14:34:50.710 12064 12064 D BoundBrokerSvc: onUnbind: Intent { act=com.google.android.gms.feedback.internal.IFeedbackService dat=chimera-action: cmp=com.google.android.gms/.chimera.GmsBoundBrokerService } 05-03 14:34:51.302 7094 7094 D BoundBrokerSvc: onUnbind: Intent { act=com.google.android.gms.icing.LIGHTWEIGHT_INDEX_SERVICE dat=chimera-action: cmp=com.google.android.gms/.chimera.PersistentApiService } ```

AshrafRizvi01 commented 1 year ago

Hi @d4vidi

Sorry to bother you again, did you get a chance to look at the logs?

nsimoncelli commented 1 year ago

Hello, I am having the same issue. Is there any updates or fixes?

d4vidi commented 1 year ago

@AshrafRizvi01 looks like you were (almost) right, Android instrumentation fails to launch:

05-03 14:34:42.531 12533 12533 E AndroidRuntime: java.lang.RuntimeException: Unable to instantiate instrumentation ComponentInfo{com.economist.darwin.test/androidx.test.runner.AndroidJUnitRunner}: java.lang.ClassNotFoundException: Didn't find class "androidx.test.runner.AndroidJUnitRunner" on path: DexPathList[[zip file "/system/framework/android.test.runner.jar", zip file "/system/framework/android.test.mock.jar", zip file "/system/framework/android.test.base.jar", zip file "/data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk", zip file "/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk"],nativeLibraryDirectories=[/system/lib64, /system_ext/lib64]]
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at android.app.ActivityThread.initInstrumentation(ActivityThread.java:6879)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at android.app.ActivityThread.handleBindApplication(ActivityThread.java:6663)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at android.app.ActivityThread.access$1500(ActivityThread.java:247)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2053)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at android.os.Handler.dispatchMessage(Handler.java:106)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at android.os.Looper.loopOnce(Looper.java:201)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at android.os.Looper.loop(Looper.java:288)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at android.app.ActivityThread.main(ActivityThread.java:7839)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at java.lang.reflect.Method.invoke(Native Method)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:548)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:1003)
05-03 14:34:42.531 12533 12533 E AndroidRuntime: Caused by: java.lang.ClassNotFoundException: Didn't find class "androidx.test.runner.AndroidJUnitRunner" on path: DexPathList[[zip file "/system/framework/android.test.runner.jar", zip file "/system/framework/android.test.mock.jar", zip file "/system/framework/android.test.base.jar", zip file "/data/app/~~IAQxEJA_tEklTSqAbn7Qsg==/com.economist.darwin.test-FIp3x3nhNkqiv3DVUdSsEA==/base.apk", zip file "/data/app/~~7GFNqVggKWVp0UvLyPvNrw==/com.economist.darwin-WwIGg96ZVdMMNYsrYaIByg==/base.apk"],nativeLibraryDirectories=[/system/lib64, /system_ext/lib64]]
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at dalvik.system.BaseDexClassLoader.findClass(BaseDexClassLoader.java:218)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at java.lang.ClassLoader.loadClass(ClassLoader.java:379)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at java.lang.ClassLoader.loadClass(ClassLoader.java:312)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    at android.app.ActivityThread.initInstrumentation(ActivityThread.java:6875)
05-03 14:34:42.531 12533 12533 E AndroidRuntime:    ... 10 more

d4vidi commented 1 year ago

I've never come across this specific issue; Are you 100% certain you've installed the test APK on the test device/emulator?

nsimoncelli commented 1 year ago

@d4vidi & @AshrafRizvi01 I fixed this by updating my testInstrumentationRunner to androidx.test.runner.AndroidJUnitRunner

I had to check the emulator logs and saw it was erroring out because a class did not exist. The app would install on the emulator but fail to launch and immediately fail all the tests.

AshrafRizvi01 commented 1 year ago

I've never come across this specific issue; Are you 100% certain you've installed the test APK on the test device/emulator?

Yes @d4vidi , I have installed the test binary. I can see it on the device. I am not sure how the same code works with the old binary but the new binary gets stuck.

d4vidi commented 1 year ago

@AshrafRizvi01 Sorry, I have nothing off the top of my head. There was nothing particularly Android-ish about the v20 release IIRC. Could you try to do the same with the basic v20 (20.0.0), just so as to try to get a better reference point?

In parallel, please try to inspect the content of your newly created test APK in android studio -- see if indeed AndroidJUnitRunner is there or not. Could you by any chance building the test APK in release mode and therefore accidentally hitting code minification/obfuscation?

d4vidi commented 1 year ago

@d4vidi & @AshrafRizvi01 I fixed this by updating my testInstrumentationRunner to androidx.test.runner.AndroidJUnitRunner

I had to check the emulator logs and saw it was erroring out because a class did not exist. The app would install on the emulator but fail to launch and immediately fail all the tests.

@nsimoncelli I don't entirely follow, as androidx.test.runner.AndroidJUnitRunner is what you're suppose to be using for testInstrumentationRunner according to our setup guide.

AshrafRizvi01 commented 1 year ago

@d4vidi & @AshrafRizvi01 I fixed this by updating my testInstrumentationRunner to androidx.test.runner.AndroidJUnitRunner I had to check the emulator logs and saw it was erroring out because a class did not exist. The app would install on the emulator but fail to launch and immediately fail all the tests.

@nsimoncelli I don't entirely follow, as androidx.test.runner.AndroidJUnitRunner is what you're suppose to be using for testInstrumentationRunner according to our setup guide.

Yes, I am already using it. I also added a dependency androidx.test:runner just in case it was not getting the classes. Still the same problem.

AshrafRizvi01 commented 1 year ago

Hi @d4vidi I have tried adding a testRunner dependency. I also tried changing the emulator and also on the detox 20.0.0. I could not find anything changed at all from my previous setup. And still getting the "class not found" issue. The last thing I can do with the old and new test binary is to compare the dex. This one works:

This one does not.

stale[bot] commented 1 year ago

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. If you believe the issue is still relevant, please test on the latest Detox and report back.

Thank you for your contributions!

For more information on bots in this repository, read this discussion.

stale[bot] commented 1 year ago

The issue has been closed for inactivity.

litserkovna commented 1 month ago

Any Update about this issue ?

wix / Detox