Back to build
Raw log | Switch to full mode | Login

adb logcat -d -v threadtime in dir /b/swarming/w/ir/k: allow_subannotations: False cmd: ['adb', 'logcat', '-d', '-v', 'threadtime'] env: {'ADB': '/b/swarming/w/ir/k/prebuilts/runtime/adb', 'ANDROID_BUILD_TOP': '/b/swarming/w/ir/k', 'ANDROID_PRODUCT_OUT': '/b/swarming/w/ir/k/out/target/product/arm_krait', 'ART_BUILD_HOST_DEBUG': 'false', 'ART_HEAP_POISONING': 'false', 'ART_TEST_ANDROID_RUNTIME_ROOT': '/system', 'ART_TEST_CHROOT': '/data/local/art-test-chroot', 'ART_TEST_KEEP_GOING': 'true', 'ART_TEST_NO_SYNC': 'true', 'ART_TEST_RUN_TEST_2ND_ARCH': 'false', 'ART_USE_GENERATIONAL_CC': 'true', 'ART_USE_READ_BARRIER': 'true', 'LANG': 'en_US.UTF-8', 'PATH': '/b/swarming/w/ir/k/out/host/linux-x86/bin:/b/swarming/w/ir/k/prebuilts/jdk/jdk9/linux-x86/bin:/b/swarming/w/ir/k/prebuilts/runtime:%(PATH)s', 'SOONG_ALLOW_MISSING_DEPENDENCIES': 'true', 'TARGET_BUILD_TYPE': 'release', 'TARGET_BUILD_VARIANT': 'eng', 'TARGET_PRODUCT': 'arm_krait', 'TEMPORARY_DISABLE_PATH_RESTRICTIONS': 'true', 'USE_DEX2OAT_DEBUG': 'false'} env_prefixes: {} env_suffixes: {} infra_step: False name_tokens: ('test baseline: adb logcat',) ok_ret: frozenset([0]) trigger_specs: () full environment: ADB: /b/swarming/w/ir/k/prebuilts/runtime/adb ADB_LIBUSB: 0 ANDROID_BUILD_TOP: /b/swarming/w/ir/k ANDROID_PRODUCT_OUT: /b/swarming/w/ir/k/out/target/product/arm_krait ART_BUILD_HOST_DEBUG: false ART_HEAP_POISONING: false ART_TEST_ANDROID_RUNTIME_ROOT: /system ART_TEST_CHROOT: /data/local/art-test-chroot ART_TEST_KEEP_GOING: true ART_TEST_NO_SYNC: true ART_TEST_RUN_TEST_2ND_ARCH: false ART_USE_GENERATIONAL_CC: true ART_USE_READ_BARRIER: true BOTO_CONFIG: /b/swarming/w/ir/tmp/gsutil_task/.boto BUILDBUCKET_EXPERIMENTAL: TRUE CHROMIUM_REV: e41aeaed051c8cc401e0bce2a6d57dcc330808e6 CIPD_CACHE_DIR: /b/swarming/cipd_cache/cache CIPD_PROTOCOL: v2 DEBIAN_FRONTEND: noninteractive DEVSHELL_CLIENT_PORT: 37249 DOCKER_CONFIG: /b/swarming/w/ir/tmp/docker_cfg_task DOCKER_HOST_HOSTNAME: build348-a9.labs.chromium.org DOCKER_TMPDIR: /b/swarming/w/ir/tmp/docker_tmp_task GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOME: /home/chrome-bot HOSTNAME: build348-a9--device1 INFRA_GIT_WRAPPER_HOME: /b/swarming/w/ir/tmp/git_home_task ISOLATED_CACHE_SIZE: 88041570304 LANG: en_US.UTF-8 LANGUAGE: en_US:en LC_ALL: en_US.UTF-8 LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8917989708338690928 LOGDOG_STREAM_PROJECT: art LOGDOG_STREAM_SERVER_PATH: unix:/b/swarming/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/swarming/w/itvXCdMF/luci_context.328693577 MAC_CHROMIUM_TMPDIR: /b/swarming/w/ir/tmp/t MAIL: /var/mail/chrome-bot NO_GCE_CHECK: False NUM_CONFIGURED_CONTAINERS: 3 PATH: /b/swarming/w/ir/k/out/host/linux-x86/bin:/b/swarming/w/ir/k/prebuilts/jdk/jdk9/linux-x86/bin:/b/swarming/w/ir/k/prebuilts/runtime:/b/swarming/w/ir/cipd_bin_packages:/b/swarming/w/ir/cipd_bin_packages/bin:/b/swarming/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games PWD: /b/swarming/w/ir/k PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 3 SOONG_ALLOW_MISSING_DEPENDENCIES: true SWARMING_BOT_ID: build348-a9--device1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43cef9c76d6e7811 SWARM_URL: https://chromium-swarm.appspot.com/bot_code TARGET_BUILD_TYPE: release TARGET_BUILD_VARIANT: eng TARGET_PRODUCT: arm_krait TEMP: /b/swarming/w/ir/tmp/t TEMPDIR: /b/swarming/w/ir/tmp/t TEMPORARY_DISABLE_PATH_RESTRICTIONS: true TMP: /b/swarming/w/ir/tmp/t TMPDIR: /b/swarming/w/ir/tmp/t USER: chrome-bot USE_DEX2OAT_DEBUG: false VPYTHON_VIRTUALENV_ROOT: /b/swarming/w/ir/cache/vpython _: /b/swarming/w/ir/cipd_bin_packages/vpython --------- beginning of main 06-15 00:54:08.950 5042 5133 I PlayCommon: [160] com.google.android.play.a.g.e(909): Preparing logs for uploading 06-15 00:54:08.954 5042 5133 W PlayCommon: [160] com.google.android.play.a.g.a(1253): No account for auth token provided 06-15 00:54:08.954 5042 5133 I PlayCommon: [160] com.google.android.play.a.g.a(1049): Connecting to server: https://play.googleapis.com/play/log?format=raw&proto_v2=true 06-15 00:54:08.960 5042 5133 E PlayCommon: [160] com.google.android.play.a.g.a(1060): Failed to connect to server: java.net.UnknownHostException: Unable to resolve host "play.googleapis.com": No address associated with hostname --------- beginning of system 06-15 00:59:05.414 938 3396 I ActivityManager: Killing 20234:com.qualcomm.timeservice/1000 (adj 906): empty for 3544s 06-15 00:59:05.430 938 952 I ProcessStatsService: Prepared write state in 11ms 06-15 00:59:05.440 938 952 I ProcessStatsService: Prepared write state in 5ms 06-15 00:59:05.467 938 938 I ActivityManager: Start proc 29045:com.google.android.storagemanager/u0a39 for service com.google.android.storagemanager/com.android.storagemanager.automatic.AutomaticStorageManagementJobService 06-15 00:59:05.485 938 938 I ActivityManager: Start proc 29059:com.google.android.dialer/u0a22 for service com.google.android.dialer/com.android.dialer.shortcuts.PeriodicJobService 06-15 00:59:05.508 938 938 I ActivityManager: Start proc 29072:android.process.media/u0a14 for service com.android.providers.downloads/.DownloadIdleService 06-15 00:59:05.513 938 949 D ActivityManager: cleanUpApplicationRecord -- 20234 06-15 00:59:05.519 29045 29045 W System : ClassLoader referenced unknown path: /system/priv-app/StorageManagerGoogle/lib/arm64 06-15 00:59:05.530 7306 29042 E cr_omaha: Failed to contact server: 06-15 00:59:05.571 29059 29059 W System : ClassLoader referenced unknown path: /system/priv-app/GoogleDialer/lib/arm64 06-15 00:59:05.576 29072 29072 W System : ClassLoader referenced unknown path: /system/priv-app/DownloadProvider/lib/arm64 06-15 00:59:05.615 29072 29072 W System : ClassLoader referenced unknown path: /system/priv-app/MediaProvider/lib/arm64 06-15 00:59:05.617 29072 29072 D ApplicationLoaders: ignored Vulkan layer search path /system/priv-app/MediaProvider/lib/arm64:/system/priv-app/MediaProvider/MediaProvider.apk!/lib/arm64-v8a:/system/lib64:/vendor/lib64 for namespace 0x7bb58f50f0 06-15 00:59:05.646 4721 29102 I EventLogChimeraService: Aggregate from 14257045297 (log), 14257045297 (data) 06-15 00:59:05.653 29045 29045 I AsmJobService: Skipping automatic storage management. 06-15 00:59:05.746 4429 4881 W GCoreFlp: No location to return for getLastLocation() 06-15 00:59:05.748 4429 4443 W FusedLocationProvider: location=null 06-15 00:59:05.763 29072 29111 D DownloadManager: Removed 0 stale downloads 06-15 00:59:05.765 29059 29059 I Dialer : GoogleDialerApplication.onCreate - enter 06-15 00:59:05.767 29111 29111 W sAsyncHandlerTh: type=1400 audit(0.0:7): avc: denied { getattr } for path="/data/cache/backup" dev="dm-2" ino=457860 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:cache_private_backup_file:s0 tclass=dir permissive=0 06-15 00:59:05.767 29111 29111 W sAsyncHandlerTh: type=1400 audit(0.0:8): avc: denied { getattr } for path="/data/cache/backup" dev="dm-2" ino=457860 scontext=u:r:priv_app:s0:c512,c768 tcontext=u:object_r:cache_private_backup_file:s0 tclass=dir permissive=0 06-15 00:59:05.774 29072 29111 D DownloadManager: Found 0 files in database 06-15 00:59:05.774 29072 29111 D DownloadManager: Found 0 files on disk 06-15 00:59:05.778 938 4506 I ActivityManager: Killing 4659:com.google.android.googlequicksearchbox:search/u0a43 (adj 906): empty for 2500s 06-15 00:59:05.797 938 4583 I ActivityManager: Start proc 29120:com.google.android.gms.feedback/u0a20 for service com.google.android.gms/.feedback.FeedbackService 06-15 00:59:05.836 29059 29059 I Dialer : ShortcutsJobScheduler.scheduleAllJobs - enter 06-15 00:59:05.839 29059 29059 I Dialer : ShortcutsJobScheduler.scheduleAllJobs - enabling shortcuts 06-15 00:59:05.841 29059 29059 I Dialer : PeriodicJobService.schedulePeriodicJob - enter 06-15 00:59:05.843 29120 29120 W System : ClassLoader referenced unknown path: /system/priv-app/PrebuiltGmsCore/lib/arm64 06-15 00:59:05.846 29059 29059 I Dialer : PeriodicJobService.schedulePeriodicJob - job already scheduled. 06-15 00:59:05.850 29059 29059 I Dialer : GoogleDialerApplication.onCreate - register new client 06-15 00:59:05.854 938 4762 D ConnectivityService: ConnectivityService NetworkRequestInfo binderDied(NetworkRequest [ LISTEN id=9, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED] ], android.os.BinderProxy@a00fcb6) 06-15 00:59:05.855 938 3553 D ActivityManager: cleanUpApplicationRecord -- 4659 06-15 00:59:05.856 938 3536 E ConnectivityService: RemoteException caught trying to send a callback msg for NetworkRequest [ LISTEN id=9, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED] ] 06-15 00:59:05.860 29059 29059 I Dialer : GoogleDialerApplication.onCreate - registered new client 06-15 00:59:05.871 29059 29059 I Dialer : PeriodicJobService.onStartJob - enter 06-15 00:59:05.890 29059 29141 I Dialer : RefreshShortcutsTask.doInBackground - enter 06-15 00:59:05.890 29059 29141 I Dialer : DynamicShortcuts.updateIcons - enter 06-15 00:59:05.897 29120 29120 W linker : /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk!/lib/arm64-v8a/libgmscore.so: unused DT entry: type 0x7ffffffd arg 0x885 06-15 00:59:05.902 29120 29120 W linker : /system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk!/lib/arm64-v8a/libconscrypt_gmscore_jni.so: unused DT entry: type 0x7ffffffd arg 0x169 06-15 00:59:05.902 29120 29120 V JNIHelp : Registering com/google/android/gms/org/conscrypt/NativeCrypto's 239 native methods... 06-15 00:59:05.906 29059 29141 I Dialer : DynamicShortcuts.updateIcons - updating 0 shortcut icons 06-15 00:59:05.910 29059 29059 I Dialer : Flags.register - phenotype register status: Status{statusCode=SUCCESS, resolution=null} 06-15 00:59:05.910 29059 29141 I Dialer : PinnedShortcuts.refresh - enter 06-15 00:59:05.915 29059 29059 I Dialer : RefreshShortcutsTask.onPostExecute - enter 06-15 00:59:05.916 29120 29120 D NetworkSecurityConfig: No Network Security Config specified, using platform default 06-15 00:59:05.919 29120 29120 I ProviderInstaller: Installed default security provider GmsCore_OpenSSL 06-15 00:59:05.950 29120 29134 W System : ClassLoader referenced unknown path: 06-15 00:59:05.951 29120 29134 W System : ClassLoader referenced unknown path: /system/priv-app/PrebuiltGmsCore/lib/arm64 06-15 00:59:05.952 29120 29134 D ApplicationLoaders: ignored Vulkan layer search path /system/priv-app/PrebuiltGmsCore/lib/arm64:/system/fake-libs64:/system/priv-app/PrebuiltGmsCore/PrebuiltGmsCore.apk!/lib/arm64-v8a:/system/lib64:/vendor/lib64 for namespace 0x7bba8390f0 06-15 00:59:05.953 29120 29134 I DynamiteModule: Considering local module com.google.android.gms.googlecertificates:2 and remote module com.google.android.gms.googlecertificates:2 06-15 00:59:05.953 29120 29134 I DynamiteModule: Selected remote version of com.google.android.gms.googlecertificates, version >= 2 06-15 00:59:06.023 29120 29134 D GoogleCertificatesImpl: Fetched 363 Google certificates 06-15 00:59:06.248 938 964 I ActivityManager: Start proc 29160:com.android.defcontainer/u0a12 for service com.android.defcontainer/.DefaultContainerService 06-15 00:59:06.710 938 29094 I PackageManager.DexOptimizer: Running dexopt (dex2oat) on: /vendor/app/datastatusnotification/datastatusnotification.apk pkg=com.qti.qualcomm.datastatusnotification isa=arm64 vmSafeMode=false debuggable=false target-filter=speed oatDir = null sharedLibraries=/system/framework/qcrilhook.jar 06-15 00:59:06.753 29198 29198 I dex2oat : /system/bin/dex2oat --compiler-filter=speed -j4 06-15 00:59:06.942 29198 29198 I dex2oat : dex2oat took 190.306ms (threads: 4) arena alloc=1331KB (1363704B) java alloc=74KB (76520B) native alloc=1089KB (1115176B) free=2MB (3079128B) 06-15 00:59:07.383 938 29094 I PackageManager.DexOptimizer: Running dexopt (dex2oat) on: /vendor/app/ims/ims.apk pkg=org.codeaurora.ims isa=arm64 vmSafeMode=false debuggable=false target-filter=speed-profile oatDir = null sharedLibraries=null 06-15 00:59:07.425 29215 29215 I dex2oat : /system/bin/dex2oat --compiler-filter=speed-profile -j4 --profile-file-fd=8 06-15 00:59:07.734 29215 29215 I dex2oat : Explicit concurrent mark sweep GC freed 906(242KB) AllocSpace objects, 0(0B) LOS objects, 99% free, 888B/1024KB, paused 140us total 9.395ms 06-15 00:59:08.020 29215 29215 I dex2oat : dex2oat took 596.163ms (threads: 4) arena alloc=35KB (35952B) java alloc=8KB (8784B) native alloc=1302KB (1333392B) free=2MB (2860912B) 06-15 00:59:08.035 938 964 W art : Long monitor contention with owner BackgroundDexOptService_IdleOptimization (29094) at int com.android.server.pm.PackageManagerService.performDexOptInternal(java.lang.String, boolean, java.lang.String, boolean)(PackageManagerService.java:7424) waiters=0 in void com.android.server.pm.PackageManagerService$MeasureParams.handleStartCopy() for 683ms 06-15 00:59:08.747 938 29094 I PackageManager.DexOptimizer: Running dexopt (dex2oat) on: /vendor/app/QXDMLoggerV2/QXDMLoggerV2.apk pkg=com.android.qxdmloggerv2 isa=arm64 vmSafeMode=false debuggable=false target-filter=speed-profile oatDir = null sharedLibraries=null 06-15 00:59:08.786 29238 29238 I dex2oat : /system/bin/dex2oat --compiler-filter=speed-profile -j4 --profile-file-fd=8 06-15 00:59:09.062 5042 5133 I PlayCommon: [160] com.google.android.play.a.g.e(909): Preparing logs for uploading 06-15 00:59:09.065 5042 5133 W PlayCommon: [160] com.google.android.play.a.g.a(1253): No account for auth token provided 06-15 00:59:09.066 5042 5133 I PlayCommon: [160] com.google.android.play.a.g.a(1049): Connecting to server: https://play.googleapis.com/play/log?format=raw&proto_v2=true 06-15 00:59:09.073 5042 5133 E PlayCommon: [160] com.google.android.play.a.g.a(1060): Failed to connect to server: java.net.UnknownHostException: Unable to resolve host "play.googleapis.com": No address associated with hostname 06-15 00:59:09.083 29238 29241 W dex2oat : Before Android 4.1, method android.graphics.PorterDuffColorFilter android.support.graphics.drawable.VectorDrawableCompat.updateTintFilter(android.graphics.PorterDuffColorFilter, android.content.res.ColorStateList, android.graphics.PorterDuff$Mode) would have incorrectly overridden the package-private method in android.graphics.drawable.Drawable 06-15 00:59:09.110 29238 29242 W dex2oat : Before Android 4.1, method int android.support.v7.widget.ListViewCompat.lookForSelectablePosition(int, boolean) would have incorrectly overridden the package-private method in android.widget.ListView 06-15 00:59:09.426 29238 29238 I dex2oat : Explicit concurrent mark sweep GC freed 5507(2MB) AllocSpace objects, 0(0B) LOS objects, 99% free, 920B/1024KB, paused 176us total 10.631ms 06-15 00:59:09.776 29238 29238 I dex2oat : dex2oat took 991.335ms (threads: 4) arena alloc=6KB (7040B) java alloc=8KB (8816B) native alloc=2MB (3069696B) free=2MB (2173184B) 06-15 00:59:09.791 938 964 W art : Long monitor contention with owner BackgroundDexOptService_IdleOptimization (29094) at int com.android.server.pm.PackageManagerService.performDexOptInternal(java.lang.String, boolean, java.lang.String, boolean)(PackageManagerService.java:7424) waiters=0 in void com.android.server.pm.PackageManagerService$MeasureParams.handleStartCopy() for 1.066s 06-15 00:59:10.609 938 29094 I PackageManager.DexOptimizer: Running dexopt (dex2oat) on: /system/priv-app/GoogleServicesFramework/GoogleServicesFramework.apk pkg=com.google.android.gsf isa=arm64 vmSafeMode=false debuggable=false target-filter=speed-profile oatDir = null sharedLibraries=null 06-15 00:59:10.648 29262 29262 I dex2oat : /system/bin/dex2oat --compiler-filter=speed-profile -j4 --profile-file-fd=8 06-15 00:59:10.708 4429 4881 W GCoreFlp: No location to return for getLastLocation() 06-15 00:59:10.708 4429 4896 W FusedLocationProvider: location=null 06-15 00:59:11.143 29262 29262 I dex2oat : Explicit concurrent mark sweep GC freed 4748(1330KB) AllocSpace objects, 0(0B) LOS objects, 99% free, 968B/1024KB, paused 152us total 8.788ms 06-15 00:59:11.460 29262 29262 I dex2oat : dex2oat took 812.953ms (threads: 4) arena alloc=5KB (5552B) java alloc=8KB (8864B) native alloc=2MB (2545568B) free=3MB (3221600B) 06-15 00:59:11.477 938 964 W art : Long monitor contention with owner BackgroundDexOptService_IdleOptimization (29094) at int com.android.server.pm.PackageManagerService.performDexOptInternal(java.lang.String, boolean, java.lang.String, boolean)(PackageManagerService.java:7424) waiters=0 in void com.android.server.pm.PackageManagerService$MeasureParams.handleStartCopy() for 896ms 06-15 01:04:09.173 5042 5133 I PlayCommon: [160] com.google.android.play.a.g.e(909): Preparing logs for uploading 06-15 01:04:09.189 5042 5133 W PlayCommon: [160] com.google.android.play.a.g.a(1253): No account for auth token provided 06-15 01:04:09.189 5042 5133 I PlayCommon: [160] com.google.android.play.a.g.a(1049): Connecting to server: https://play.googleapis.com/play/log?format=raw&proto_v2=true 06-15 01:04:09.193 5042 5133 E PlayCommon: [160] com.google.android.play.a.g.a(1060): Failed to connect to server: java.net.UnknownHostException: Unable to resolve host "play.googleapis.com": No address associated with hostname 06-15 01:09:09.295 5042 5133 I PlayCommon: [160] com.google.android.play.a.g.e(909): Preparing logs for uploading 06-15 01:09:09.301 5042 5133 W PlayCommon: [160] com.google.android.play.a.g.a(1253): No account for auth token provided 06-15 01:09:09.302 5042 5133 I PlayCommon: [160] com.google.android.play.a.g.a(1049): Connecting to server: https://play.googleapis.com/play/log?format=raw&proto_v2=true 06-15 01:09:09.314 5042 5133 E PlayCommon: [160] com.google.android.play.a.g.a(1060): Failed to connect to server: java.net.UnknownHostException: Unable to resolve host "play.googleapis.com": No address associated with hostname