[01:46:06.720] New invocation is queued and will start shortly
[01:46:07.832] Starting the invocation (attempt 1)
[01:46:07.866] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:46:07.866] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:46:07.866] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9094989459492993312",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"7344a24efd2a222d6d45b709767cd4d050af10a3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9094989459492993312",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/7344a24efd2a222d6d45b709767cd4d050af10a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7344a24efd2a222d6d45b709767cd4d050af10a3",
"gitiles_ref:refs/heads/master"
]
}
[01:46:09.204] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1542505568173039",
"id": "8929559397305786336",
"lease_expiration_ts": "1542624368968800",
"lease_key": "1311136867",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7344a24efd2a222d6d45b709767cd4d050af10a3\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1542505569109550",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/3386",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/7344a24efd2a222d6d45b709767cd4d050af10a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7344a24efd2a222d6d45b709767cd4d050af10a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9094989459492993312",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_dimension:builder:Deterministic Linux (dbg)",
"swarming_dimension:caches:builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Deterministic Linux (dbg)/3386",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8929559397305786336",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:da5491be675c79d05750cef9051038aa905ddaff",
"swarming_tag:builder:Deterministic Linux (dbg)",
"swarming_tag:buildset:commit/git/7344a24efd2a222d6d45b709767cd4d050af10a3",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7344a24efd2a222d6d45b709767cd4d050af10a3",
"swarming_tag:caches:builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8929559397305786336/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9094989459492993312",
"swarming_tag:scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:c01a4256238f4a37a408b9311cb94873f8b819e5",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:413d50a1a4482510",
"user_agent:luci-scheduler"
],
"updated_ts": "1542505569109650",
"url": "https://ci.chromium.org/b/8929559397305786336",
"utcnow_ts": "1542505569178209"
}
}
[01:46:09.204] Task URL: https://ci.chromium.org/b/8929559397305786336
[01:46:09.204] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:2:0) after 1m0s
[01:46:09.725] Received PubSub notification, asking Buildbucket for the build status
[01:46:09.763] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:09.224] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:2:0)
[01:47:09.224] Timer tick, asking Buildbucket for the build status
[01:47:09.284] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:09.284] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:4:0) after 1m0s
[01:48:09.303] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:4:0)
[01:48:09.303] Timer tick, asking Buildbucket for the build status
[01:48:09.376] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:09.376] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:5:0) after 1m0s
[01:49:09.393] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:5:0)
[01:49:09.393] Timer tick, asking Buildbucket for the build status
[01:49:09.471] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:09.471] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:6:0) after 1m0s
[01:50:09.511] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:6:0)
[01:50:09.511] Timer tick, asking Buildbucket for the build status
[01:50:09.594] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:09.594] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:7:0) after 1m0s
[01:51:09.610] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:7:0)
[01:51:09.610] Timer tick, asking Buildbucket for the build status
[01:51:09.761] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:09.761] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:8:0) after 1m0s
[01:52:09.777] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:8:0)
[01:52:09.777] Timer tick, asking Buildbucket for the build status
[01:52:09.938] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:09.938] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:9:0) after 1m0s
[01:53:09.984] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:9:0)
[01:53:09.984] Timer tick, asking Buildbucket for the build status
[01:53:10.053] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:10.053] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:10:0) after 1m0s
[01:54:10.184] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:10:0)
[01:54:10.184] Timer tick, asking Buildbucket for the build status
[01:54:10.367] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:10.367] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:11:0) after 1m0s
[01:55:10.387] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:11:0)
[01:55:10.387] Timer tick, asking Buildbucket for the build status
[01:55:10.452] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:10.453] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:12:0) after 1m0s
[01:56:10.471] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:12:0)
[01:56:10.471] Timer tick, asking Buildbucket for the build status
[01:56:10.521] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:10.521] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:13:0) after 1m0s
[01:57:10.537] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:13:0)
[01:57:10.537] Timer tick, asking Buildbucket for the build status
[01:57:10.637] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:10.637] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:14:0) after 1m0s
[01:58:10.654] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:14:0)
[01:58:10.654] Timer tick, asking Buildbucket for the build status
[01:58:10.782] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:10.782] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:15:0) after 1m0s
[01:59:10.802] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:15:0)
[01:59:10.802] Timer tick, asking Buildbucket for the build status
[01:59:10.927] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:10.927] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:16:0) after 1m0s
[02:00:10.955] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:16:0)
[02:00:10.955] Timer tick, asking Buildbucket for the build status
[02:00:11.013] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:11.013] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:17:0) after 1m0s
[02:01:11.041] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:17:0)
[02:01:11.041] Timer tick, asking Buildbucket for the build status
[02:01:11.121] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:11.121] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:18:0) after 1m0s
[02:02:11.137] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:18:0)
[02:02:11.137] Timer tick, asking Buildbucket for the build status
[02:02:11.201] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:11.201] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:19:0) after 1m0s
[02:03:11.354] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:19:0)
[02:03:11.354] Timer tick, asking Buildbucket for the build status
[02:03:11.585] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:11.585] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:20:0) after 1m0s
[02:04:11.602] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:20:0)
[02:04:11.602] Timer tick, asking Buildbucket for the build status
[02:04:11.660] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:11.660] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:21:0) after 1m0s
[02:05:11.681] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:21:0)
[02:05:11.681] Timer tick, asking Buildbucket for the build status
[02:05:11.843] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:11.843] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:22:0) after 1m0s
[02:06:11.862] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:22:0)
[02:06:11.862] Timer tick, asking Buildbucket for the build status
[02:06:11.967] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:11.967] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:23:0) after 1m0s
[02:07:11.989] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:23:0)
[02:07:11.989] Timer tick, asking Buildbucket for the build status
[02:07:12.031] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:12.031] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:24:0) after 1m0s
[02:08:12.066] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:24:0)
[02:08:12.066] Timer tick, asking Buildbucket for the build status
[02:08:12.303] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:12.303] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:25:0) after 1m0s
[02:09:12.324] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:25:0)
[02:09:12.324] Timer tick, asking Buildbucket for the build status
[02:09:12.382] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:12.383] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:26:0) after 1m0s
[02:10:12.419] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:26:0)
[02:10:12.419] Timer tick, asking Buildbucket for the build status
[02:10:12.539] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:12.539] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:27:0) after 1m0s
[02:11:12.559] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:27:0)
[02:11:12.559] Timer tick, asking Buildbucket for the build status
[02:11:12.787] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:12.787] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:28:0) after 1m0s
[02:12:12.808] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:28:0)
[02:12:12.808] Timer tick, asking Buildbucket for the build status
[02:12:12.893] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:12.893] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:29:0) after 1m0s
[02:13:12.985] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:29:0)
[02:13:12.985] Timer tick, asking Buildbucket for the build status
[02:13:13.104] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:13.104] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:30:0) after 1m0s
[02:14:13.125] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:30:0)
[02:14:13.125] Timer tick, asking Buildbucket for the build status
[02:14:13.226] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:13.226] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:31:0) after 1m0s
[02:15:13.245] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:31:0)
[02:15:13.245] Timer tick, asking Buildbucket for the build status
[02:15:13.320] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:13.320] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:32:0) after 1m0s
[02:16:13.344] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:32:0)
[02:16:13.344] Timer tick, asking Buildbucket for the build status
[02:16:13.395] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:13.395] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:33:0) after 1m0s
[02:17:13.415] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:33:0)
[02:17:13.415] Timer tick, asking Buildbucket for the build status
[02:17:13.490] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:13.490] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:34:0) after 1m0s
[02:18:13.511] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:34:0)
[02:18:13.511] Timer tick, asking Buildbucket for the build status
[02:18:13.571] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:13.571] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:35:0) after 1m0s
[02:19:13.605] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:35:0)
[02:19:13.605] Timer tick, asking Buildbucket for the build status
[02:19:13.894] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:13.894] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:36:0) after 1m0s
[02:20:13.930] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:36:0)
[02:20:13.930] Timer tick, asking Buildbucket for the build status
[02:20:14.125] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:14.125] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:37:0) after 1m0s
[02:21:14.169] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:37:0)
[02:21:14.169] Timer tick, asking Buildbucket for the build status
[02:21:14.364] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:14.364] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:38:0) after 1m0s
[02:22:14.778] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:38:0)
[02:22:14.778] Timer tick, asking Buildbucket for the build status
[02:22:14.877] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:14.877] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:39:0) after 1m0s
[02:23:15.623] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:39:0)
[02:23:15.623] Timer tick, asking Buildbucket for the build status
[02:23:15.792] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:15.792] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:40:0) after 1m0s
[02:24:16.405] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:40:0)
[02:24:16.405] Timer tick, asking Buildbucket for the build status
[02:24:16.484] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:16.484] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:41:0) after 1m0s
[02:25:17.306] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:41:0)
[02:25:17.306] Timer tick, asking Buildbucket for the build status
[02:25:17.711] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:17.711] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:42:0) after 1m0s
[02:26:18.615] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:42:0)
[02:26:18.615] Timer tick, asking Buildbucket for the build status
[02:26:18.834] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:18.834] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:43:0) after 1m0s
[02:27:18.977] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:43:0)
[02:27:18.977] Timer tick, asking Buildbucket for the build status
[02:27:19.040] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:19.040] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:44:0) after 1m0s
[02:28:20.007] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:44:0)
[02:28:20.007] Timer tick, asking Buildbucket for the build status
[02:28:20.176] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:20.176] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:45:0) after 1m0s
[02:29:20.819] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:45:0)
[02:29:20.819] Timer tick, asking Buildbucket for the build status
[02:29:21.002] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:21.002] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:46:0) after 1m0s
[02:30:21.682] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:46:0)
[02:30:21.682] Timer tick, asking Buildbucket for the build status
[02:30:21.742] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:21.742] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:47:0) after 1m0s
[02:31:21.928] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:47:0)
[02:31:21.928] Timer tick, asking Buildbucket for the build status
[02:31:22.186] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:22.186] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:48:0) after 1m0s
[02:32:23.493] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:48:0)
[02:32:23.493] Timer tick, asking Buildbucket for the build status
[02:32:23.563] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:23.563] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:49:0) after 1m0s
[02:33:24.006] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:49:0)
[02:33:24.006] Timer tick, asking Buildbucket for the build status
[02:33:24.166] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:24.166] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:50:0) after 1m0s
[02:34:24.246] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:50:0)
[02:34:24.246] Timer tick, asking Buildbucket for the build status
[02:34:24.391] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:24.391] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:51:0) after 1m0s
[02:35:24.884] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:51:0)
[02:35:24.884] Timer tick, asking Buildbucket for the build status
[02:35:24.960] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:24.960] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:52:0) after 1m0s
[02:36:25.029] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:52:0)
[02:36:25.029] Timer tick, asking Buildbucket for the build status
[02:36:25.103] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:25.103] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:53:0) after 1m0s
[02:37:25.395] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:53:0)
[02:37:25.395] Timer tick, asking Buildbucket for the build status
[02:37:25.455] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:25.455] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:54:0) after 1m0s
[02:38:26.341] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:54:0)
[02:38:26.341] Timer tick, asking Buildbucket for the build status
[02:38:26.407] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:26.408] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:55:0) after 1m0s
[02:39:27.083] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:55:0)
[02:39:27.083] Timer tick, asking Buildbucket for the build status
[02:39:27.145] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:27.145] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:56:0) after 1m0s
[02:40:27.660] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:56:0)
[02:40:27.660] Timer tick, asking Buildbucket for the build status
[02:40:27.698] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:27.698] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:57:0) after 1m0s
[02:41:28.207] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:57:0)
[02:41:28.207] Timer tick, asking Buildbucket for the build status
[02:41:28.285] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:28.285] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:58:0) after 1m0s
[02:42:28.603] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:58:0)
[02:42:28.603] Timer tick, asking Buildbucket for the build status
[02:42:28.696] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:28.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:59:0) after 1m0s
[02:43:28.725] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:59:0)
[02:43:28.725] Timer tick, asking Buildbucket for the build status
[02:43:28.784] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:28.784] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:60:0) after 1m0s
[02:44:29.715] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:60:0)
[02:44:29.715] Timer tick, asking Buildbucket for the build status
[02:44:29.863] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:29.863] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:61:0) after 1m0s
[02:45:30.407] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:61:0)
[02:45:30.407] Timer tick, asking Buildbucket for the build status
[02:45:30.474] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:30.474] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:62:0) after 1m0s
[02:46:30.612] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:62:0)
[02:46:30.612] Timer tick, asking Buildbucket for the build status
[02:46:30.713] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:30.713] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:63:0) after 1m0s
[02:47:31.567] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:63:0)
[02:47:31.567] Timer tick, asking Buildbucket for the build status
[02:47:31.610] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:31.610] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:64:0) after 1m0s
[02:48:32.792] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:64:0)
[02:48:32.792] Timer tick, asking Buildbucket for the build status
[02:48:32.896] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:32.896] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:65:0) after 1m0s
[02:49:34.227] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:65:0)
[02:49:34.227] Timer tick, asking Buildbucket for the build status
[02:49:34.322] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:34.322] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:66:0) after 1m0s
[02:50:34.386] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:66:0)
[02:50:34.386] Timer tick, asking Buildbucket for the build status
[02:50:34.438] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:34.438] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:67:0) after 1m0s
[02:51:34.458] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:67:0)
[02:51:34.458] Timer tick, asking Buildbucket for the build status
[02:51:34.523] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:34.523] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:68:0) after 1m0s
[02:52:35.003] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:68:0)
[02:52:35.003] Timer tick, asking Buildbucket for the build status
[02:52:35.163] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:35.163] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:69:0) after 1m0s
[02:53:35.463] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:69:0)
[02:53:35.463] Timer tick, asking Buildbucket for the build status
[02:53:35.533] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:35.533] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:70:0) after 1m0s
[02:54:36.686] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:70:0)
[02:54:36.686] Timer tick, asking Buildbucket for the build status
[02:54:36.909] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:36.909] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:71:0) after 1m0s
[02:55:37.162] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:71:0)
[02:55:37.162] Timer tick, asking Buildbucket for the build status
[02:55:37.227] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:37.227] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:72:0) after 1m0s
[02:56:37.962] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:72:0)
[02:56:37.962] Timer tick, asking Buildbucket for the build status
[02:56:38.097] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:38.097] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:73:0) after 1m0s
[02:57:39.843] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:73:0)
[02:57:39.843] Timer tick, asking Buildbucket for the build status
[02:57:40.003] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:40.003] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:74:0) after 1m0s
[02:58:40.032] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:74:0)
[02:58:40.032] Timer tick, asking Buildbucket for the build status
[02:58:40.094] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:40.094] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:75:0) after 1m0s
[02:59:40.117] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:75:0)
[02:59:40.117] Timer tick, asking Buildbucket for the build status
[02:59:40.191] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:40.191] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:76:0) after 1m0s
[03:00:40.217] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:76:0)
[03:00:40.217] Timer tick, asking Buildbucket for the build status
[03:00:40.422] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:40.422] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:77:0) after 1m0s
[03:01:40.521] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:77:0)
[03:01:40.521] Timer tick, asking Buildbucket for the build status
[03:01:40.589] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:40.589] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:78:0) after 1m0s
[03:02:40.646] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:78:0)
[03:02:40.646] Timer tick, asking Buildbucket for the build status
[03:02:41.049] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:41.049] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:79:0) after 1m0s
[03:03:41.071] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:79:0)
[03:03:41.071] Timer tick, asking Buildbucket for the build status
[03:03:41.137] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:41.137] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:80:0) after 1m0s
[03:04:41.158] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:80:0)
[03:04:41.158] Timer tick, asking Buildbucket for the build status
[03:04:41.260] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:41.260] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:81:0) after 1m0s
[03:05:41.288] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:81:0)
[03:05:41.288] Timer tick, asking Buildbucket for the build status
[03:05:41.362] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:41.362] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:82:0) after 1m0s
[03:06:41.413] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:82:0)
[03:06:41.413] Timer tick, asking Buildbucket for the build status
[03:06:41.477] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:41.477] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:83:0) after 1m0s
[03:07:41.501] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:83:0)
[03:07:41.501] Timer tick, asking Buildbucket for the build status
[03:07:41.600] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:41.600] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:84:0) after 1m0s
[03:08:41.632] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:84:0)
[03:08:41.632] Timer tick, asking Buildbucket for the build status
[03:08:41.732] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:41.732] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:85:0) after 1m0s
[03:09:41.751] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:85:0)
[03:09:41.751] Timer tick, asking Buildbucket for the build status
[03:09:41.790] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:41.790] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:86:0) after 1m0s
[03:10:41.812] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:86:0)
[03:10:41.813] Timer tick, asking Buildbucket for the build status
[03:10:42.068] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:42.068] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:87:0) after 1m0s
[03:11:42.092] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:87:0)
[03:11:42.092] Timer tick, asking Buildbucket for the build status
[03:11:42.216] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:42.216] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:88:0) after 1m0s
[03:12:42.299] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:88:0)
[03:12:42.299] Timer tick, asking Buildbucket for the build status
[03:12:42.379] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:42.379] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:89:0) after 1m0s
[03:13:42.405] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:89:0)
[03:13:42.405] Timer tick, asking Buildbucket for the build status
[03:13:42.482] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:42.482] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:90:0) after 1m0s
[03:14:42.563] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:90:0)
[03:14:42.563] Timer tick, asking Buildbucket for the build status
[03:14:42.631] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:42.631] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:91:0) after 1m0s
[03:15:42.652] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:91:0)
[03:15:42.652] Timer tick, asking Buildbucket for the build status
[03:15:42.805] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:42.805] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:92:0) after 1m0s
[03:16:42.847] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:92:0)
[03:16:42.847] Timer tick, asking Buildbucket for the build status
[03:16:42.957] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:42.957] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:93:0) after 1m0s
[03:17:42.997] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:93:0)
[03:17:42.997] Timer tick, asking Buildbucket for the build status
[03:17:43.059] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:43.059] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:94:0) after 1m0s
[03:18:43.095] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:94:0)
[03:18:43.095] Timer tick, asking Buildbucket for the build status
[03:18:43.156] Build 8929559397305786336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:43.156] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9094989459492993312:95:0) after 1m0s
[03:18:48.870] Received PubSub notification, asking Buildbucket for the build status
[03:18:49.013] Build 8929559397305786336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:18:49.013] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1542511127864567",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1542505568173039",
"id": "8929559397305786336",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7344a24efd2a222d6d45b709767cd4d050af10a3\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Deterministic Linux (dbg)\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3844-124e68f\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"swarm627-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"99673869a3cd8731d924bd32fa486feebfdc6c4f\", \"got_swarming_client_revision\": \"7f463e66e1c4bb830ef3de9d046aa227d28e1b00\", \"got_revision\": \"7344a24efd2a222d6d45b709767cd4d050af10a3\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"69b44ee6a1269afb5c7622189de236af0c4ec2a7\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25677}\", \"$build/goma\": {\"jobs\": 500}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"got_revision_cp\": \"refs/heads/master@{#609129}\", \"branch\": \"refs/heads/master\", \"revision\": \"7344a24efd2a222d6d45b709767cd4d050af10a3\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Deterministic Linux (dbg)\", \"got_webrtc_revision\": \"179a3923b9e402f427728d52b3024a3de1696a66\", \"mastername\": \"chromium.linux\", \"got_angle_revision\": \"778bf09deea44363f5b9aa5a8795e264936099c3\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1542505568173039, \"tags\": [\"builder:Deterministic Linux (dbg)\", \"buildset:commit/git/7344a24efd2a222d6d45b709767cd4d050af10a3\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7344a24efd2a222d6d45b709767cd4d050af10a3\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9094989459492993312\", \"scheduler_job_id:chromium/Deterministic Linux (dbg)\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8929559397305786336\"}}, \"got_v8_revision\": \"9a0639762e7ada6acab150e3097d4ded62caf9de\", \"got_v8_revision_cp\": \"refs/heads/7.2.356@{#2}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 3386, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"7c0917180523833965f3652816f7bc8daf04c9e5\", \"gpu_perftests\": \"cbcc1911a39d031e3026632d18a326681951c602\", \"telemetry_unittests\": \"2fc3e1b4e6cfdb6e4d35b81a3075533202fa28e6\", \"load_library_perf_tests\": \"07870d9e60ea7bc8de5f9fe99641894d5a51fdd3\", \"devtools_closure_compile\": \"67c67a6330b0e9423599424a9dae8d91f9b80d12\", \"chromedriver_py_tests\": \"85f16e02f92e77f1b3b71848dfdf269e4b48b51e\", \"mojo_core_unittests\": \"295bf9349d54de1758f0d7931976caeba2226e2b\", \"leveldb_service_unittests\": \"ddf6efb10484e71b3cc63c5107c0e9ac5679878b\", \"storage_unittests\": \"462f8e00a83f592ca02d89d3716fa29e3558736c\", \"jingle_unittests\": \"58513f8b6c15e21b29f76888eb69414632645753\", \"angle_unittests\": \"e843a625a36826e500e80f0d2f23d0fbb931f34b\", \"cast_unittests\": \"4f6024c2db41f2cc541a8cc9790031c035fdcd8d\", \"cc_unittests\": \"dbb47716aad274d22a4e58b129f9ed47c76c7d8b\", \"blink_common_unittests\": \"2d948df8bf5a4077dc7dbe4d3a9210a3a31d3695\", \"crashpad_tests\": \"e6c2dec412eb8db3cca0ab969ab8618273419c52\", \"telemetry_perf_tests_without_chrome\": \"7155ff76436ccd3142a549d4badd5ee9a3c45e20\", \"metrics_python_tests\": \"e6d38ad9f88b3c09dbca34b5cf7aed24b945a71d\", \"message_center_unittests\": \"8c0e28a5d208c6c4a15efe11465ba9b6b25f476c\", \"ppapi_unittests\": \"2717ada7b24cab46031abfa2585896723b070e5d\", \"extensions_unittests\": \"327df3cca8181340ab68d05723b8bf3f2eb1237c\", \"gl_unittests_ozone\": \"2276338f46b8c377faace2281bc8b543202cb026\", \"app_shell_unittests\": \"f486026a7f6cdf245f3ad405a6694980fdf68638\", \"boringssl_ssl_tests\": \"4840c1931eb77b1cf6bbbe7beced43175f3757be\", \"chrome_app_unittests\": \"fb71e033aafaa20bad3f4da31eba12b5cd6fbf84\", \"gl_unittests\": \"4270bc734682b3d28f12089ab51ee9941d64402f\", \"gcm_unit_tests\": \"ca071fc61c6de10698b1ee29f5e0c8bbf338ff10\", \"views_unittests\": \"d199ebf76f3d51861238bd5e00ac61c30419ad5d\", \"content_shell_crash_test\": \"891bac14141f0500c637a3c1ffea91c3edbddce9\", \"dawn_end2end_tests\": \"434062da75f20a34e1dbfbb8539bae01513477ce\", \"ui_base_unittests\": \"bc54770dad6069eaf8229740ec24bd9132e17923\", \"gles2_conform_test\": \"3dfd4caa8b5ecb095e470fbc8b1e45729d9cabef\", \"cronet_unittests\": \"b75b8957cea926a276b1a6d39d40ec7771bc428e\", \"performance_test_suite\": \"b61f43716411ad146c95a44967b9957bf6ce6648\", \"wm_unittests\": \"ca2d565da01bdb221d3210f3ba916b3f68667b58\", \"boringssl_crypto_tests\": \"b7d491666b3afa36e24eb638e7b6a70db9031acd\", \"gin_unittests\": \"d52b6478dd4a1195725e91fc87a66eddbfa488f4\", \"performance_browser_tests\": \"f23d55a882841f56bee7ea1385d30b07980e4e06\", \"extensions_browsertests\": \"09637d7c98be468c7393e1b8d10d27e848cbea5c\", \"webkit_python_tests\": \"bcbd3ef2041d33d76a5e9afb570a541d6b82c053\", \"headless_unittests\": \"4247c60f0ef2614f0bff75a712416437c2c58963\", \"webkit_layout_tests\": \"3b3b9bc39529caa8a1cc31ab2c0845751ec4f3e5\", \"breakpad_unittests\": \"878ebb37df96a612f959389f691f9a94c693f27b\", \"vr_pixeltests\": \"daacd4aef5993d4a239da32302c00be918ded80e\", \"webkit_unit_tests\": \"1542b5ec7de738fa56ff0b6453834569b11e07f8\", \"content_unittests\": \"f2a4ba3f630cdd98b74b5820ab0c5dd8e64898ed\", \"performance_webview_test_suite\": \"761d645c7d9a7421ea1f2f8298d67af0fc2041b3\", \"wtf_unittests\": \"aed77825eda82eed5ac8f8c45e5b887aaee4c14e\", \"webkit_layout_tests_exparchive\": \"4f5a2b5929cf34145428631e425705803323ddba\", \"traffic_annotation_auditor_unittests\": \"6e642869e485709a16f859e8c73a96c627dacc59\", \"libjingle_xmpp_unittests\": \"d2559ca1b151b65487b013bf0b0e958542c847ab\", \"remoting_unittests\": \"c42102241c2e3d784a3ba01d28bac314ec73fc69\", \"vr_common_perftests\": \"ca4626ca90b3c3c4d28921532a8e4c9cf13ad31d\", \"filesystem_service_unittests\": \"65ce0b20db6ed833e1645915480e54e6a7b8e8f9\", \"gl_tests\": \"2a5e956fc7bfa0b49be07b1f94900cefda3fcfbc\", \"base_perftests\": \"54217770e3f2e5adfeb7859a76b214ea569fd709\", \"headless_browsertests\": \"f6f93c91eb24f49084be039817ee4b11a247e068\", \"capture_unittests\": \"cf5277332231ec8f8118f8cf27f77952f515ed33\", \"telemetry_gpu_unittests\": \"01a75ddee8ddc7770085b45043ace94159514f8d\", \"command_buffer_perftests\": \"11f6e6fc29ef0b6defc68e1b8f268407349a5120\", \"media_unittests\": \"34d860f92aa4a6ceec6374cd4192ec40d97580a1\", \"gfx_unittests\": \"280fb31391ac4f44bcf22b40d10c44e3a0968a16\", \"chromedriver_unittests\": \"c30576de3f710f545440e2cab22b0d1e80cf4f66\", \"views_mus_interactive_ui_tests\": \"f6a2ae8cf7f9f0b53383adab75ea3b24c2d23dbd\", \"sandbox_linux_unittests\": \"99e93b33e18464817c4ae6971b500b1f4bfc72f1\", \"media_service_unittests\": \"636e1d8453da567b1b893711bce7e9807ef023a7\", \"compositor_unittests\": \"8d8275b1082760195949310a129ed34a3394df7d\", \"latency_unittests\": \"6a99209bb5e55d3f0176f97baea456dc632980c7\", \"swiftshader_unittests\": \"9b4c97676a75bd76e2e604de6512ea1499f93c7e\", \"telemetry_perf_unittests\": \"d6ae8713d36614d8a8c6265492af109aa8a02d2d\", \"media_blink_unittests\": \"c563bf221ffe19ae114a6e63e4249ab555051c53\", \"views_mus_unittests\": \"86752d89eea61b3ce2d317e926c90e39ce2aee91\", \"components_unittests\": \"4ec5198d240490ab3dc90fdd34a349daa63256ca\", \"snapshot_unittests\": \"78641c7ca2edd0308da30f5c5297364c0697388e\", \"accessibility_unittests\": \"2d41e2542644c90712f36247b0af64b0a3cf8631\", \"net_perftests\": \"6ed565a9920a7f58da0deab800070e055174cd34\", \"aura_unittests\": \"07c0f675554e94d3a4ee4300ad64f724e3b23955\", \"services_unittests\": \"724093963a879126e1ec76db928ac4c1c6f4251a\", \"components_perftests\": \"f57fdf45e6eaee218207fb5903113ae69f6df420\", \"events_unittests\": \"dc24e4cf1cc272494ee8b9e7db80d44f79849367\", \"tracing_perftests\": \"2ba38bb8e19b2e862e09d48ca2940011a90ef7ec\", \"chromedriver_replay_unittests\": \"80dcdd65faa3e5c2519bd1b938064835370c9584\", \"ui_touch_selection_unittests\": \"b9dfd340455a8d93560567c2777ab6065425674c\", \"cacheinvalidation_unittests\": \"3885a566783d97fc80ab6af7e608094b915cf63c\", \"viz_unittests\": \"f085cbc86a38b86d464356136975322fe6f15748\", \"browser_tests\": \"beb7c91841dc623da7e2caddaf2b031bfcdf8bcd\", \"net_unittests\": \"cc75405062ceece5bc89163cc276a4b3d5fc924f\", \"pdf_unittests\": \"43eae6d2e94162c70ee4533ae034f0a4359b55d9\", \"dbus_unittests\": \"f211c9c67826cb11a6a7ca61ab44bc90fa746fad\", \"vr_common_unittests\": \"f83dfefcdd76e43da2e5ea2735a42579ea5acd38\", \"gpu_unittests\": \"2f9871fb7a4e2a561ead082cb0139569099493a1\", \"native_theme_unittests\": \"c1fc1ff902638ebee19010a00c80271c7d365009\", \"interactive_ui_tests\": \"10ddb3c410b3dcb436125114c76a8e8f62701104\", \"mojo_unittests\": \"b4601cec1a8fe2610f3d4405b1003e5383b5070a\", \"url_unittests\": \"536b3f021691b778d7e502b985b640471f75d0d4\", \"media_perftests\": \"c108c54c351d24f7015d464e1475c6240c82a41b\", \"cronet_tests\": \"467506f92e854773ea43beb8b9462cd1c4017fc0\", \"google_apis_unittests\": \"8948e68c36e307bbac0a5917734a78fc3d5674bd\", \"telemetry_gpu_integration_test\": \"e77444145943328ef9c667430917c696441e7332\", \"blink_heap_unittests\": \"44767eb5c09b59e27e5cf72c7adb1e1b151260ad\", \"midi_unittests\": \"c43998d35c97429f58da668da05eb98a2bb969c2\", \"base_unittests\": \"f55d86b27e1f74fb0bdb5f0f936da279d6bf9a8a\", \"sync_integration_tests\": \"2e590aa6800905d6f4c6b521980e10fd8942bdd2\", \"sql_unittests\": \"dd8503216fa3f3aaa9045c7fe7c0037fd8f29d53\", \"nacl_helper_nonsfi_unittests\": \"40074290c3b97a2d8e6388098d103c4fd3c5a45a\", \"blink_fuzzer_unittests\": \"58bec163587201479e3498efdeda14e27a2718f0\", \"zucchini_unittests\": \"58f2f15c9c0995207b0b3f0e35e004adc129229c\", \"ipc_tests\": \"534226f1710af51602757997690779de304b27b0\", \"courgette_unittests\": \"1b146278a0dc3eb22a6c0b13810198ff1ef39e5c\", \"devtools_eslint\": \"ddfa6ede77059cf5db6720b97283240ecdef6733\", \"blink_platform_unittests\": \"28113608b21c204a25fb30db1548551e6a30eff9\", \"components_browsertests\": \"37306edc4438054da0a90c4d3d0a7cfe77924c1d\", \"views_perftests\": \"55a6c5ddb0fcdb637e9f571054c3afc051b06744\", \"angle_perftests\": \"140cb61e60c98b200926f3625dc5b37631bc9153\", \"display_unittests\": \"0a937c947943c9c9d785f91272e31fea737fb545\", \"service_manager_unittests\": \"233d962c0d5157be415233d4ff7c4fed308162c3\", \"angle_end2end_tests\": \"4e2dca7265043acb19bfb93f59e11a706eaa113b\", \"skia_unittests\": \"ea30e8aea4f02c8254df9d54bed92d15f1018ed2\", \"angle_white_box_tests\": \"a659299606223ee7e79be4040e2c1f267395eca5\", \"crypto_unittests\": \"fa2855775c6b3b2771b3eb8b8afd89b2528dd693\", \"nacl_loader_unittests\": \"ad337996fe491da3cac3aadaf5f6e1e4d7f140ea\", \"unit_tests\": \"cc7b3a7286aabc9514b94c08f97464a9926c41d9\", \"content_browsertests\": \"2ee98219bebd8b7503d3775d959f4ab1160b312e\", \"printing_unittests\": \"efdead402618bc17595760f2854e7d30c3fe8155\", \"device_unittests\": \"1ab98ec74c3e35a649ef6b41efa4e081216fa99f\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm627-c4\", \"got_buildtools_revision\": \"13a00f110ef910a25763346d6538b60f12845656\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1542505569042308",
"status": "COMPLETED",
"status_changed_ts": "1542511128557157",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/3386",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/7344a24efd2a222d6d45b709767cd4d050af10a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7344a24efd2a222d6d45b709767cd4d050af10a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9094989459492993312",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_dimension:builder:Deterministic Linux (dbg)",
"swarming_dimension:caches:builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Deterministic Linux (dbg)/3386",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8929559397305786336",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:da5491be675c79d05750cef9051038aa905ddaff",
"swarming_tag:builder:Deterministic Linux (dbg)",
"swarming_tag:buildset:commit/git/7344a24efd2a222d6d45b709767cd4d050af10a3",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7344a24efd2a222d6d45b709767cd4d050af10a3",
"swarming_tag:caches:builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8929559397305786336/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9094989459492993312",
"swarming_tag:scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:c01a4256238f4a37a408b9311cb94873f8b819e5",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:413d50a1a4482510",
"user_agent:luci-scheduler"
],
"updated_ts": "1542511128559219",
"url": "https://ci.chromium.org/b/8929559397305786336",
"utcnow_ts": "1542511128987958"
}
}
[03:18:49.013] Invocation finished in 1h32m42.309895397s with status SUCCEEDED