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/silvermont', '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': 'silvermont', 'TEMPORARY_DISABLE_PATH_RESTRICTIONS': 'true', 'USE_DEX2OAT_DEBUG': 'false'} env_prefixes: {} env_suffixes: {} infra_step: False name_tokens: ('test debuggable: 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/silvermont 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: 38406 DOCKER_CONFIG: /b/swarming/w/ir/tmp/docker_cfg_task DOCKER_HOST_HOSTNAME: build25-b9.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: build25-b9--device3 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/8918573079176802144 LOGDOG_STREAM_PROJECT: art LOGDOG_STREAM_SERVER_PATH: unix:/b/swarming/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/swarming/w/itu8yKAE/luci_context.374839131 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: build25-b9--device3 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43add09ca1b45311 SWARM_URL: https://chromium-swarm.appspot.com/bot_code TARGET_BUILD_TYPE: release TARGET_BUILD_VARIANT: eng TARGET_PRODUCT: silvermont 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 03-19 12:25:20.667 3127 3412 I DeviceStateChecker: screenOn: true, isCharging: true 03-19 12:28:20.712 3127 3412 I DeviceStateChecker: screenOn: true, isCharging: true 03-19 12:31:20.757 3127 3412 I DeviceStateChecker: screenOn: true, isCharging: true 03-19 12:34:20.801 3127 3412 I DeviceStateChecker: screenOn: true, isCharging: true 03-19 12:35:36.772 791 810 I WifiService: requestActivityInfo uid=1000 03-19 12:35:36.772 791 810 I WifiService: reportActivityInfo uid=1000 03-19 12:35:36.772 791 810 I WifiService: getSupportedFeatures uid=1000 --------- beginning of system 03-19 12:35:36.775 791 810 E BatteryExternalStatsWorker: no controller energy info supplied for wifi 03-19 12:35:36.775 791 810 E BatteryExternalStatsWorker: no controller energy info supplied for bluetooth 03-19 12:35:49.427 791 791 I EntropyMixer: Added HW RNG output to entropy pool 03-19 12:35:49.427 791 791 I EntropyMixer: Writing entropy... 03-19 12:37:00.061 791 806 I ProcessStatsService: Pruning old procstats: /data/system/procstats/state-2019-03-18-09-40-53.bin 03-19 12:37:20.848 3127 3412 I DeviceStateChecker: screenOn: true, isCharging: true 03-19 12:40:20.892 3127 3412 I DeviceStateChecker: screenOn: true, isCharging: true 03-19 12:43:20.937 3127 3412 I DeviceStateChecker: screenOn: true, isCharging: true 03-19 12:44:18.642 3199 7461 I EventLogChimeraService: Aggregate from 1552997658490 (log), 1552997658490 (data) 03-19 12:44:48.784 6026 7698 I Babel_ConcService: Acquired partial wake lock to keep ConcurrentService alive 03-19 12:44:48.787 6026 7698 I Babel_ConcService: Released partial wake lock as ConcurrentService became idle 03-19 12:44:48.792 6026 7697 I Babel_ConcService: Scheduling delay with GcmNetworkManager of 82799 s for task fyfDB_CLEANUP_7751996_5 and tag :persisted 03-19 12:44:48.799 6026 7699 I Babel_ConcService: Acquired partial wake lock to keep ConcurrentService alive 03-19 12:44:48.802 6026 7699 I Babel_ConcService: Released partial wake lock as ConcurrentService became idle 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: Error inserting period=82800000 target_class=com.google.android.apps.hangouts.concurrent.impl.GcmTriggeredNotifier required_network_type=2 runtime=1552999488802 extras={"conc_wakeup_priority_group":{"0":0}} source=4 target_package=com.google.android.talk source_version=0 last_runtime=0 user_id=0 job_id=-1 requires_charging=0 tag=:persisted flex_time=82799000 task_type=0 retry_strategy={"maximum_backoff_seconds":{"3600":0},"initial_backoff_seconds":{"30":0},"retry_policy":{"0":0}} 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: android.database.sqlite.SQLiteConstraintException: UNIQUE constraint failed: pending_ops.tag, pending_ops.target_class, pending_ops.target_package, pending_ops.user_id (code 2067) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at android.database.sqlite.SQLiteConnection.nativeExecuteForLastInsertedRowId(Native Method) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at android.database.sqlite.SQLiteConnection.executeForLastInsertedRowId(SQLiteConnection.java:783) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at android.database.sqlite.SQLiteSession.executeForLastInsertedRowId(SQLiteSession.java:788) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at android.database.sqlite.SQLiteStatement.executeInsert(SQLiteStatement.java:86) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at android.database.sqlite.SQLiteDatabase.insertWithOnConflict(SQLiteDatabase.java:1548) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at android.database.sqlite.SQLiteDatabase.insert(SQLiteDatabase.java:1417) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at svr.a(:com.google.android.gms@11580448:208) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at swy.a(:com.google.android.gms@11580448:64) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at swy.a(:com.google.android.gms@11580448:7) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at swy.a(:com.google.android.gms@11580448:58) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at svo.run(:com.google.android.gms@11580448:3) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at mmq.run(:com.google.android.gms@11580448:25) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1162) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:636) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at mrx.run(Unknown Source:7) 03-19 12:44:48.817 2898 3457 E SQLiteDatabase: at java.lang.Thread.run(Thread.java:764) 03-19 12:44:48.818 2898 3457 E NetworkScheduler: Error persisting task: com.google.android.talk/com.google.android.apps.hangouts.concurrent.impl.GcmTriggeredNotifier{u=0 tag=":persisted" trigger=window{start=82799s,end=82800s,earliest=82798s,latest=82799s} requirements=[NET_ANY] attributes=[PERSISTED] scheduled=0s last_run=N/A jid=N/A status=PENDING retries=0} 03-19 12:46:20.982 3127 3412 I DeviceStateChecker: screenOn: true, isCharging: true 03-19 12:49:21.027 3127 3412 I DeviceStateChecker: screenOn: true, isCharging: true 03-19 12:52:21.072 3127 3412 I DeviceStateChecker: screenOn: true, isCharging: true