[18:04:37.059] New invocation is queued and will start shortly
[18:04:38.157] Starting the invocation (attempt 1)
[18:04:38.204] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:04:38.204] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:04:38.204] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018554654562512672",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"593cd8af12091bc84a36972c6be5f0f2043952c1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9018554654562512672",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/593cd8af12091bc84a36972c6be5f0f2043952c1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/593cd8af12091bc84a36972c6be5f0f2043952c1",
"gitiles_ref:refs/heads/master"
]
}
[18:04:39.033] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615399478327102",
"id": "8853124592572024976",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"593cd8af12091bc84a36972c6be5f0f2043952c1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615399478735647",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46057",
"builder:linux-chromeos-rel",
"buildset:commit/git/593cd8af12091bc84a36972c6be5f0f2043952c1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/593cd8af12091bc84a36972c6be5f0f2043952c1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018554654562512672",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8853124592572024976/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1615399478735825",
"url": "https://ci.chromium.org/b/8853124592572024976",
"utcnow_ts": "1615399479026165"
}
}
[18:04:39.033] Task URL: https://ci.chromium.org/b/8853124592572024976
[18:04:39.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:2:0) after 1m0s
[18:05:39.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:2:0)
[18:05:39.068] Timer tick, asking Buildbucket for the build status
[18:05:39.342] Build 8853124592572024976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:05:39.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:3:0) after 1m0s
[18:06:39.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:3:0)
[18:06:39.367] Timer tick, asking Buildbucket for the build status
[18:06:39.434] Build 8853124592572024976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:06:39.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:4:0) after 1m0s
[18:07:39.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:4:0)
[18:07:39.465] Timer tick, asking Buildbucket for the build status
[18:07:39.594] Build 8853124592572024976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:07:39.594] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:5:0) after 1m0s
[18:08:39.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:5:0)
[18:08:39.674] Timer tick, asking Buildbucket for the build status
[18:08:39.748] Build 8853124592572024976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:08:39.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:6:0) after 1m0s
[18:09:39.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:6:0)
[18:09:39.876] Timer tick, asking Buildbucket for the build status
[18:09:40.044] Build 8853124592572024976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:09:40.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:7:0) after 1m0s
[18:10:40.180] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:7:0)
[18:10:40.180] Timer tick, asking Buildbucket for the build status
[18:10:40.273] Build 8853124592572024976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:10:40.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:8:0) after 1m0s
[18:11:40.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:8:0)
[18:11:40.539] Timer tick, asking Buildbucket for the build status
[18:11:40.612] Build 8853124592572024976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:11:40.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:9:0) after 1m0s
[18:12:42.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:9:0)
[18:12:42.206] Timer tick, asking Buildbucket for the build status
[18:12:42.608] Build 8853124592572024976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:12:42.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:10:0) after 1m0s
[18:13:42.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:10:0)
[18:13:42.668] Timer tick, asking Buildbucket for the build status
[18:13:42.744] Build 8853124592572024976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:13:42.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:11:0) after 1m0s
[18:14:43.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:11:0)
[18:14:43.173] Timer tick, asking Buildbucket for the build status
[18:14:43.434] Build 8853124592572024976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:14:43.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:12:0) after 1m0s
[18:15:07.512] Received PubSub notification, asking Buildbucket for the build status
[18:15:07.619] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:43.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:12:0)
[18:15:43.562] Timer tick, asking Buildbucket for the build status
[18:15:43.831] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:43.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:14:0) after 1m0s
[18:16:43.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:14:0)
[18:16:43.873] Timer tick, asking Buildbucket for the build status
[18:16:43.943] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:43.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:15:0) after 1m0s
[18:17:43.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:15:0)
[18:17:43.987] Timer tick, asking Buildbucket for the build status
[18:17:44.226] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:44.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:16:0) after 1m0s
[18:18:44.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:16:0)
[18:18:44.306] Timer tick, asking Buildbucket for the build status
[18:18:44.366] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:44.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:17:0) after 1m0s
[18:19:44.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:17:0)
[18:19:44.726] Timer tick, asking Buildbucket for the build status
[18:19:44.804] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:44.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:18:0) after 1m0s
[18:20:44.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:18:0)
[18:20:44.873] Timer tick, asking Buildbucket for the build status
[18:20:44.944] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:44.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:19:0) after 1m0s
[18:21:45.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:19:0)
[18:21:45.013] Timer tick, asking Buildbucket for the build status
[18:21:45.159] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:45.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:20:0) after 1m0s
[18:22:45.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:20:0)
[18:22:45.413] Timer tick, asking Buildbucket for the build status
[18:22:45.656] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:45.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:21:0) after 1m0s
[18:23:45.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:21:0)
[18:23:45.652] Timer tick, asking Buildbucket for the build status
[18:23:45.715] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:45.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:22:0) after 1m0s
[18:24:45.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:22:0)
[18:24:45.779] Timer tick, asking Buildbucket for the build status
[18:24:45.871] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:45.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:23:0) after 1m0s
[18:25:46.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:23:0)
[18:25:46.030] Timer tick, asking Buildbucket for the build status
[18:25:46.232] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:46.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:24:0) after 1m0s
[18:26:46.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:24:0)
[18:26:46.282] Timer tick, asking Buildbucket for the build status
[18:26:46.372] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:46.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:25:0) after 1m0s
[18:27:46.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:25:0)
[18:27:46.646] Timer tick, asking Buildbucket for the build status
[18:27:46.929] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:46.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:26:0) after 1m0s
[18:28:47.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:26:0)
[18:28:47.057] Timer tick, asking Buildbucket for the build status
[18:28:47.457] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:47.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:27:0) after 1m0s
[18:29:47.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:27:0)
[18:29:47.517] Timer tick, asking Buildbucket for the build status
[18:29:47.607] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:47.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:28:0) after 1m0s
[18:30:47.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:28:0)
[18:30:47.630] Timer tick, asking Buildbucket for the build status
[18:30:47.936] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:47.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:29:0) after 1m0s
[18:31:47.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:29:0)
[18:31:47.978] Timer tick, asking Buildbucket for the build status
[18:31:48.083] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:48.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:30:0) after 1m0s
[18:32:48.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:30:0)
[18:32:48.136] Timer tick, asking Buildbucket for the build status
[18:32:48.513] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:48.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:31:0) after 1m0s
[18:33:48.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:31:0)
[18:33:48.480] Timer tick, asking Buildbucket for the build status
[18:33:48.571] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:48.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:32:0) after 1m0s
[18:34:48.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:32:0)
[18:34:48.589] Timer tick, asking Buildbucket for the build status
[18:34:48.679] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:48.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:33:0) after 1m0s
[18:35:48.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:33:0)
[18:35:48.725] Timer tick, asking Buildbucket for the build status
[18:35:48.790] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:48.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:34:0) after 1m0s
[18:36:48.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:34:0)
[18:36:48.825] Timer tick, asking Buildbucket for the build status
[18:36:49.109] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:49.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:35:0) after 1m0s
[18:37:49.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:35:0)
[18:37:49.122] Timer tick, asking Buildbucket for the build status
[18:37:49.247] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:49.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:36:0) after 1m0s
[18:38:49.246] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:36:0)
[18:38:49.246] Timer tick, asking Buildbucket for the build status
[18:38:49.310] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:49.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:37:0) after 1m0s
[18:39:49.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:37:0)
[18:39:49.367] Timer tick, asking Buildbucket for the build status
[18:39:49.724] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:49.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:38:0) after 1m0s
[18:40:49.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:38:0)
[18:40:49.764] Timer tick, asking Buildbucket for the build status
[18:40:49.825] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:49.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:39:0) after 1m0s
[18:41:49.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:39:0)
[18:41:49.836] Timer tick, asking Buildbucket for the build status
[18:41:49.927] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:49.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:40:0) after 1m0s
[18:42:50.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:40:0)
[18:42:50.006] Timer tick, asking Buildbucket for the build status
[18:42:50.092] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:50.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:41:0) after 1m0s
[18:43:50.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:41:0)
[18:43:50.089] Timer tick, asking Buildbucket for the build status
[18:43:50.329] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:50.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:42:0) after 1m0s
[18:44:50.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:42:0)
[18:44:50.347] Timer tick, asking Buildbucket for the build status
[18:44:50.437] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:50.437] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:43:0) after 1m0s
[18:45:50.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:43:0)
[18:45:50.501] Timer tick, asking Buildbucket for the build status
[18:45:50.578] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:50.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:44:0) after 1m0s
[18:46:50.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:44:0)
[18:46:50.616] Timer tick, asking Buildbucket for the build status
[18:46:50.835] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:50.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:45:0) after 1m0s
[18:47:50.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:45:0)
[18:47:50.873] Timer tick, asking Buildbucket for the build status
[18:47:50.949] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:50.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:46:0) after 1m0s
[18:48:50.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:46:0)
[18:48:50.938] Timer tick, asking Buildbucket for the build status
[18:48:51.016] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:51.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:47:0) after 1m0s
[18:49:51.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:47:0)
[18:49:51.104] Timer tick, asking Buildbucket for the build status
[18:49:51.194] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:51.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:48:0) after 1m0s
[18:50:51.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:48:0)
[18:50:51.192] Timer tick, asking Buildbucket for the build status
[18:50:51.397] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:51.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:49:0) after 1m0s
[18:51:51.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:49:0)
[18:51:51.491] Timer tick, asking Buildbucket for the build status
[18:51:51.581] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:51.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:50:0) after 1m0s
[18:52:51.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:50:0)
[18:52:51.657] Timer tick, asking Buildbucket for the build status
[18:52:51.768] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:51.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:51:0) after 1m0s
[18:53:51.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:51:0)
[18:53:51.815] Timer tick, asking Buildbucket for the build status
[18:53:51.883] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:51.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:52:0) after 1m0s
[18:54:52.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:52:0)
[18:54:52.026] Timer tick, asking Buildbucket for the build status
[18:54:52.150] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:52.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:53:0) after 1m0s
[18:55:52.160] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:53:0)
[18:55:52.160] Timer tick, asking Buildbucket for the build status
[18:55:52.222] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:52.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:54:0) after 1m0s
[18:56:52.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:54:0)
[18:56:52.358] Timer tick, asking Buildbucket for the build status
[18:56:52.551] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:52.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:55:0) after 1m0s
[18:57:52.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:55:0)
[18:57:52.551] Timer tick, asking Buildbucket for the build status
[18:57:52.737] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:52.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:56:0) after 1m0s
[18:58:52.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:56:0)
[18:58:52.808] Timer tick, asking Buildbucket for the build status
[18:58:52.890] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:52.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:57:0) after 1m0s
[18:59:52.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:57:0)
[18:59:52.964] Timer tick, asking Buildbucket for the build status
[18:59:53.060] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:53.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:58:0) after 1m0s
[19:00:53.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:58:0)
[19:00:53.108] Timer tick, asking Buildbucket for the build status
[19:00:53.323] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:53.323] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:59:0) after 1m0s
[19:01:53.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:59:0)
[19:01:53.516] Timer tick, asking Buildbucket for the build status
[19:01:53.813] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:53.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:60:0) after 1m0s
[19:02:53.992] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:60:0)
[19:02:53.992] Timer tick, asking Buildbucket for the build status
[19:02:54.066] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:54.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:61:0) after 1m0s
[19:03:54.202] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:61:0)
[19:03:54.202] Timer tick, asking Buildbucket for the build status
[19:03:54.279] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:54.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:62:0) after 1m0s
[19:04:54.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:62:0)
[19:04:54.357] Timer tick, asking Buildbucket for the build status
[19:04:54.729] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:54.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:63:0) after 1m0s
[19:05:54.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:63:0)
[19:05:54.822] Timer tick, asking Buildbucket for the build status
[19:05:54.897] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:54.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:64:0) after 1m0s
[19:06:54.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:64:0)
[19:06:54.919] Timer tick, asking Buildbucket for the build status
[19:06:55.041] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:55.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:65:0) after 1m0s
[19:07:55.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:65:0)
[19:07:55.073] Timer tick, asking Buildbucket for the build status
[19:07:55.137] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:55.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:66:0) after 1m0s
[19:08:55.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:66:0)
[19:08:55.164] Timer tick, asking Buildbucket for the build status
[19:08:55.338] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:55.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:67:0) after 1m0s
[19:09:55.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:67:0)
[19:09:55.383] Timer tick, asking Buildbucket for the build status
[19:09:55.812] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:55.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:68:0) after 1m0s
[19:10:55.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:68:0)
[19:10:55.829] Timer tick, asking Buildbucket for the build status
[19:10:55.919] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:55.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:69:0) after 1m0s
[19:11:55.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:69:0)
[19:11:55.955] Timer tick, asking Buildbucket for the build status
[19:11:56.339] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:56.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:70:0) after 1m0s
[19:12:56.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:70:0)
[19:12:56.424] Timer tick, asking Buildbucket for the build status
[19:12:56.554] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:56.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:71:0) after 1m0s
[19:13:56.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:71:0)
[19:13:56.539] Timer tick, asking Buildbucket for the build status
[19:13:56.813] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:56.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:72:0) after 1m0s
[19:14:56.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:72:0)
[19:14:56.792] Timer tick, asking Buildbucket for the build status
[19:14:56.881] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:56.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:73:0) after 1m0s
[19:15:56.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:73:0)
[19:15:56.869] Timer tick, asking Buildbucket for the build status
[19:15:56.943] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:56.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:74:0) after 1m0s
[19:16:56.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:74:0)
[19:16:56.966] Timer tick, asking Buildbucket for the build status
[19:16:57.252] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:57.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:75:0) after 1m0s
[19:17:57.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:75:0)
[19:17:57.338] Timer tick, asking Buildbucket for the build status
[19:17:57.419] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:57.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:76:0) after 1m0s
[19:18:57.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:76:0)
[19:18:57.491] Timer tick, asking Buildbucket for the build status
[19:18:57.565] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:57.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:77:0) after 1m0s
[19:19:57.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:77:0)
[19:19:57.600] Timer tick, asking Buildbucket for the build status
[19:19:57.761] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:57.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:78:0) after 1m0s
[19:20:57.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:78:0)
[19:20:57.805] Timer tick, asking Buildbucket for the build status
[19:20:58.096] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:58.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:79:0) after 1m0s
[19:21:58.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:79:0)
[19:21:58.122] Timer tick, asking Buildbucket for the build status
[19:21:58.270] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:58.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:80:0) after 1m0s
[19:22:58.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:80:0)
[19:22:58.292] Timer tick, asking Buildbucket for the build status
[19:22:58.374] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:58.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:81:0) after 1m0s
[19:23:58.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:81:0)
[19:23:58.395] Timer tick, asking Buildbucket for the build status
[19:23:58.540] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:58.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:82:0) after 1m0s
[19:24:58.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:82:0)
[19:24:58.585] Timer tick, asking Buildbucket for the build status
[19:24:58.657] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:58.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:83:0) after 1m0s
[19:25:58.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:83:0)
[19:25:58.697] Timer tick, asking Buildbucket for the build status
[19:25:58.882] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:58.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:84:0) after 1m0s
[19:26:58.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:84:0)
[19:26:58.933] Timer tick, asking Buildbucket for the build status
[19:26:59.003] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:59.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:85:0) after 1m0s
[19:27:59.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:85:0)
[19:27:59.071] Timer tick, asking Buildbucket for the build status
[19:27:59.333] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:59.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:86:0) after 1m0s
[19:28:59.304] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:86:0)
[19:28:59.304] Timer tick, asking Buildbucket for the build status
[19:28:59.524] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:59.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:87:0) after 1m0s
[19:29:59.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:87:0)
[19:29:59.565] Timer tick, asking Buildbucket for the build status
[19:29:59.711] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:59.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:88:0) after 1m0s
[19:30:59.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:88:0)
[19:30:59.761] Timer tick, asking Buildbucket for the build status
[19:31:00.102] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:00.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:89:0) after 1m0s
[19:32:00.133] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:89:0)
[19:32:00.133] Timer tick, asking Buildbucket for the build status
[19:32:00.400] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:00.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:90:0) after 1m0s
[19:33:00.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:90:0)
[19:33:00.428] Timer tick, asking Buildbucket for the build status
[19:33:00.505] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:00.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:91:0) after 1m0s
[19:34:00.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:91:0)
[19:34:00.505] Timer tick, asking Buildbucket for the build status
[19:34:00.570] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:00.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:92:0) after 1m0s
[19:35:00.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:92:0)
[19:35:00.596] Timer tick, asking Buildbucket for the build status
[19:35:00.705] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:00.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:93:0) after 1m0s
[19:36:00.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:93:0)
[19:36:00.736] Timer tick, asking Buildbucket for the build status
[19:36:00.823] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:00.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:94:0) after 1m0s
[19:37:00.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:94:0)
[19:37:00.866] Timer tick, asking Buildbucket for the build status
[19:37:01.157] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:01.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:95:0) after 1m0s
[19:38:01.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:95:0)
[19:38:01.174] Timer tick, asking Buildbucket for the build status
[19:38:01.324] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:01.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:96:0) after 1m0s
[19:39:01.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:96:0)
[19:39:01.359] Timer tick, asking Buildbucket for the build status
[19:39:01.469] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:01.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:97:0) after 1m0s
[19:40:01.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:97:0)
[19:40:01.490] Timer tick, asking Buildbucket for the build status
[19:40:02.149] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:02.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:98:0) after 1m0s
[19:41:02.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:98:0)
[19:41:02.151] Timer tick, asking Buildbucket for the build status
[19:41:02.455] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:02.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:99:0) after 1m0s
[19:42:02.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:99:0)
[19:42:02.544] Timer tick, asking Buildbucket for the build status
[19:42:02.676] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:02.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:100:0) after 1m0s
[19:43:02.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:100:0)
[19:43:02.693] Timer tick, asking Buildbucket for the build status
[19:43:02.926] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:02.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:101:0) after 1m0s
[19:44:02.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:101:0)
[19:44:02.909] Timer tick, asking Buildbucket for the build status
[19:44:03.039] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:03.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:102:0) after 1m0s
[19:45:03.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:102:0)
[19:45:03.118] Timer tick, asking Buildbucket for the build status
[19:45:03.414] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:03.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:103:0) after 1m0s
[19:46:03.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:103:0)
[19:46:03.452] Timer tick, asking Buildbucket for the build status
[19:46:03.532] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:03.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:104:0) after 1m0s
[19:47:03.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:104:0)
[19:47:03.537] Timer tick, asking Buildbucket for the build status
[19:47:03.701] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:03.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:105:0) after 1m0s
[19:48:03.737] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:105:0)
[19:48:03.737] Timer tick, asking Buildbucket for the build status
[19:48:03.831] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:03.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:106:0) after 1m0s
[19:49:03.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:106:0)
[19:49:03.861] Timer tick, asking Buildbucket for the build status
[19:49:04.110] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:04.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:107:0) after 1m0s
[19:50:04.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:107:0)
[19:50:04.085] Timer tick, asking Buildbucket for the build status
[19:50:04.194] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:04.194] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:108:0) after 1m0s
[19:51:04.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:108:0)
[19:51:04.229] Timer tick, asking Buildbucket for the build status
[19:51:04.647] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:04.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:109:0) after 1m0s
[19:52:04.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:109:0)
[19:52:04.621] Timer tick, asking Buildbucket for the build status
[19:52:04.698] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:04.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:110:0) after 1m0s
[19:53:04.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:110:0)
[19:53:04.824] Timer tick, asking Buildbucket for the build status
[19:53:05.333] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:05.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:111:0) after 1m0s
[19:54:05.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:111:0)
[19:54:05.378] Timer tick, asking Buildbucket for the build status
[19:54:05.454] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:05.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:112:0) after 1m0s
[19:55:05.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:112:0)
[19:55:05.525] Timer tick, asking Buildbucket for the build status
[19:55:05.686] Build 8853124592572024976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:05.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018554654562512672:113:0) after 1m0s
[19:55:19.968] Received PubSub notification, asking Buildbucket for the build status
[19:55:20.054] Build 8853124592572024976: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:55:20.054] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615406119555548",
"created_by": "project:chromium",
"created_ts": "1615399478327102",
"failure_reason": "BUILD_FAILURE",
"id": "8853124592572024976",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"593cd8af12091bc84a36972c6be5f0f2043952c1\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PermissionRequestManagerBrowserTest.MultipleTabs\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"593cd8af12091bc84a36972c6be5f0f2043952c1\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-139-nnca\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46057, \"got_angle_revision\": \"b2f547f4f423f492c4483d901d1b3ad5f3ad8798\", \"got_dawn_revision\": \"6d4617a2d28d8d3d4aa946abdbabb368d025b6b7\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"593cd8af12091bc84a36972c6be5f0f2043952c1\", \"got_revision_cp\": \"refs/heads/master@{#861613}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"023914a27b634063766fee96f2e6763513f5b059\", \"got_v8_revision\": \"1eba7bc10f6caa3878c5a1fd9f45ee5056ae62ed\", \"got_v8_revision_cp\": \"refs/heads/9.1.89@{#1}\", \"got_webrtc_revision\": \"92d12707e00837a978485f731d72ef30cf19d2a2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33402}\", \"is_cached\": false, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"593cd8af12091bc84a36972c6be5f0f2043952c1\", \"swarm_hashes_refs/heads/master(at){#861613}_without_patch\": {\"absl_hardening_tests\": \"362005025f58f092e1d7768eab8fe6f76c374cf9\", \"accessibility_unittests\": \"addc2684b813cc898a670db54d90c756a584f170\", \"angle_unittests\": \"6b00a406f9b1bbb4377dfcad8dd3e55a2aa459d5\", \"app_list_unittests\": \"94825551d7f9e03a1d1fcaee037b2e73fe5ef279\", \"app_shell_unittests\": \"18e8142fa2112472c3f633b03ac4e5758248fbd1\", \"ash_unittests\": \"d71781c293245e6cbb13c269908c3a377a3ed0ed\", \"aura_unittests\": \"6077b15baabc30ce0020f1ff6cd3ffb7fae96612\", \"base_unittests\": \"1c776cdae8a417bc4a5e59b85d6c1c04b5319d4a\", \"base_util_unittests\": \"55ec6cc85d37852b1ff274a9d3eaf00a86a5fe30\", \"blink_common_unittests\": \"b456bc97b69e16d85eda66c4026284991ecb17bc\", \"blink_fuzzer_unittests\": \"9a1bb46909e62a3d2ac1d3e4dc8fa24c5223dd10\", \"blink_heap_unittests\": \"b4329dc285b6cd960e90f3d75fe32dab9fdf0357\", \"blink_platform_unittests\": \"be31f36512ebada8f11dba654afb112e09974732\", \"blink_unittests\": \"177a24feff14db73628873f945783ed5695c5d24\", \"boringssl_crypto_tests\": \"44e00de7650b065eaf29d1dc2e01a94b927d1f26\", \"boringssl_ssl_tests\": \"bed9dc3e70000be31d64db7790efa078894fd24d\", \"browser_tests\": \"c99db455a4160c98a539e1b409092684c9b8ea3d\", \"capture_unittests\": \"15cda292f85cfff9fb0a29c47c37eafee6aaf0b7\", \"cast_unittests\": \"9d6c424272bb2e15b2f6511b95fbe38bb1acbdf9\", \"cc_unittests\": \"6cd31dbaf315bc66e64256b6e1f8e9e0a6626488\", \"chrome_app_unittests\": \"13e2cef9db3f65b4fbf9d9a19f9736dd3e2f8476\", \"chromedriver_unittests\": \"bf955c62b13f636a76eb9bd034e873146fa137a2\", \"chromeos_components_unittests\": \"7ccbfdb91152d8c42598e521bb4fc76ca3d34845\", \"chromeos_unittests\": \"8cf51900926efcdd49caea8d56243ae4292027a5\", \"color_unittests\": \"37e6b752148dae3bb3512f6a625972ad9f0d712e\", \"components_browsertests\": \"6ea650c85d79890d089ffe7ae5bbc198158ad870\", \"components_unittests\": \"5b8a6b98580c8d0a11a31a4bffc30172bc2ab2ed\", \"compositor_unittests\": \"0700c6513ae6cbf98dc6f5cc4c146429f2b1f9a0\", \"content_browsertests\": \"7e80224b66ac2445c68cdf1282121cf4f9d850cc\", \"content_unittests\": \"99e1e6e733814ccb139a00dc0d2472edeae153df\", \"crashpad_tests\": \"a4b4bbd091c1aa66b9df90715e90510afdb59fc2\", \"crypto_unittests\": \"ce96f86a4463ee31c86c5efefc058e26c635674b\", \"dbus_unittests\": \"844f503cb95651e5d1eb8cd4a5636e6baba4d0c8\", \"device_unittests\": \"fd3d47b350f6ec0b7030a9e7211ccbd3738e4e0c\", \"display_unittests\": \"24f5fb2d4c2408bb1911c375bc79326a735ca25a\", \"events_unittests\": \"1e7d9545fbe8ad19024912a29563be8a478de3c1\", \"exo_unittests\": \"e117cbc3c59d6ad1a64b22ea9756ffb53b6f4f3c\", \"extensions_browsertests\": \"a5485d86a2e6768e9e97640d0b0815371d12886f\", \"extensions_unittests\": \"f2a2c652166adfa18610e660de8cfee3d512ee3b\", \"filesystem_service_unittests\": \"261605745c48605cb9e5fb070b92162e751d66f2\", \"gcm_unit_tests\": \"46b06e554a22691e2640dee6c3b7f83fb873f260\", \"gfx_unittests\": \"14ab832b3113faaee6d12172d7a51411e212e77a\", \"gin_unittests\": \"9a1269a9cd4cce79cd4d1cd4fac02e77a8f5c82f\", \"gl_unittests_ozone\": \"ac029dd9f8a9cd3d92f34b29288cabbc20e8afe6\", \"google_apis_unittests\": \"16dcaf0e37c3c3a2ab622d7e94955547b9c7cdd9\", \"gpu_unittests\": \"58dbbaf3555c737e539971f45464eae805aae7be\", \"gwp_asan_unittests\": \"a76ea8e251d38edd6953338f783f7696dd3b933b\", \"interactive_ui_tests\": \"6bf4d3935e3b8a20d43372698f19ea01251f2ac9\", \"ipc_tests\": \"42ac2679cd526dcd5ea6ccc8d74885b2d6e630bf\", \"jingle_unittests\": \"b3cdb504be2c1c030f4ce2a62d0210295a0f57fb\", \"keyboard_unittests\": \"0c7292f7ebac0395bfcdf249cdcc498ceafec455\", \"latency_unittests\": \"21f91743d330a3077e55497fab59d274ebc1d047\", \"libjingle_xmpp_unittests\": \"93e8782c4cc8d6284029887206102b4db01fa55d\", \"liburlpattern_unittests\": \"52c0a185e2b8e5aeccb88aca20ce063f679d1735\", \"media_blink_unittests\": \"86de0e8c856d197ec2e2a990923080ad17595054\", \"media_unittests\": \"7552458652b4728ba4cdc08de184c13e3b425808\", \"message_center_unittests\": \"9f75173c448d087ed2eb178d6517d6f95345777e\", \"midi_unittests\": \"ae2f6b59e68f2c19034cae52c2461746b5c336f6\", \"mojo_core_unittests\": \"b51f0a69a7824cec8c714278c73d388873c81dc5\", \"mojo_unittests\": \"c405c86a3705bad77afcd33bac159cb958f3f400\", \"nacl_helper_nonsfi_unittests\": \"4f1e94ed8e0d87c54e26d89957cd28c5ef64542a\", \"nacl_loader_unittests\": \"bff72e6df1dd5014a054f437f3e3a1c35f976e0d\", \"native_theme_unittests\": \"4cfac0747dd13641c6fadfac34c58fb10382953b\", \"net_unittests\": \"0654b2adad9e62c736a5841a2ab4bb8fde843ba3\", \"ozone_gl_unittests\": \"5ab3550f70c60c1936049803fb12ec26afc5c486\", \"ozone_unittests\": \"bb97cc52c0b5e889c0d8a12ab67726d36e6f4da4\", \"ozone_x11_unittests\": \"e908488b4b25d621dd512aa64810bae97980f450\", \"pdf_unittests\": \"5a854809714fcd50ae24924689b0c957a5af3960\", \"perfetto_unittests\": \"db3c9522c2e1a83939e4017adfba900f3521fc1f\", \"ppapi_unittests\": \"046e77189e0bf477b91412b83e860099a9722ec5\", \"printing_unittests\": \"cea21c52760af703dfef99600a15d52f2fdea751\", \"remoting_unittests\": \"6dcfbc7fe6d7aa0862f579b23ac2bc83d5252f6c\", \"sandbox_linux_unittests\": \"1523519527c05b6290e12ed33e71bb8d0a93a0bc\", \"service_manager_unittests\": \"5fb451dcf77814dd96926b6dc0744920e4d6f49f\", \"services_unittests\": \"c7929fec6b77975514b6cee4d45e59ee1418126e\", \"shell_dialogs_unittests\": \"12b488b1af98d522b967e2e380d83f8f2009e85b\", \"shell_encryption_unittests\": \"24969fec16e7c7537f705c4c6a478cf5522f450e\", \"skia_unittests\": \"5f03c4d8d73765c9e22e89efcde9f1c19f817da3\", \"snapshot_unittests\": \"21fca52022f8dc7c7d4fda6ccd0bf49d4e1491a9\", \"sql_unittests\": \"59921d9e45e25fdcab50f788d0bcf13b2f472ff4\", \"storage_unittests\": \"44a2190c541bec6345e7e284b6ac08e70e017bed\", \"sync_integration_tests\": \"45ce064a19a37bb32e98be8766fa738101d91beb\", \"traffic_annotation_auditor_unittests\": \"a4311b2b6ae9af8647be1ecfe496642187b1b686\", \"ui_base_unittests\": \"4515b73185c238d5cd48bd69f7e1d3dd2ec2fd02\", \"ui_chromeos_unittests\": \"a93c2231f6987acef78d084a79cf31dbab90eec2\", \"ui_touch_selection_unittests\": \"7c01457011c3e7df05d2e84f85442d9fe002cbbb\", \"unit_tests\": \"4d000e6e03c1b0cd5860c97aa6492ebf98bf70eb\", \"url_unittests\": \"f8b1cfca28f2ff15ffb1fe7f80220d9fb25296b1\", \"usage_time_limit_unittests\": \"6fefa7b6ff2b0b14b5eb4d49d9fc5b67c6c02137\", \"views_unittests\": \"b79c0071b69181888905ca8dbeb291a9cbe52b9f\", \"viz_unittests\": \"bbe8e37468c48bc7b1bac6563fff096f8eaca16a\", \"wayland_client_perftests\": \"1a716dc3ced3a896c1fe72c4c8c2c4a8e264a74d\", \"wayland_client_tests\": \"90ec0861b137ea91e16d7ee9de29c88c74d3e034\", \"wm_unittests\": \"991df298b4f0aa87e9f0985a9a0cd62bc888d8f3\", \"wtf_unittests\": \"311431952efb937f6fa81e093fab90bc2edce613\", \"zlib_unittests\": \"1178b6702d0b5a319811fc653b1dd1f6db8c3981\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_8424eb90fa14b61ae49b4a2af2404de06c5c2a9a9345d31da54fda5995bf0e92_v2\", \"builder_929ff5fc16a6e3805d1df535bec80a7a81511d72ae0698ab5053153c2404cba8_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_dacf669b49e3ec352df995227b58e62a545590985cca62e76e0831f920af4ccd_v2\", \"builder_dbe717e93dfefb94e765b2da7ffc34b16e5134b01772a8d37ee52c1fe1505654_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-139-nnca\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5746-91ff3bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PermissionRequestManagerBrowserTest.MultipleTabs\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615400106545671",
"status": "COMPLETED",
"status_changed_ts": "1615400106933376",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46057",
"builder:linux-chromeos-rel",
"buildset:commit/git/593cd8af12091bc84a36972c6be5f0f2043952c1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/593cd8af12091bc84a36972c6be5f0f2043952c1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018554654562512672",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8853124592572024976/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:523621795fa97910",
"user_agent:luci-scheduler"
],
"updated_ts": "1615406119555946",
"url": "https://ci.chromium.org/b/8853124592572024976",
"utcnow_ts": "1615406120045767"
}
}
[19:55:20.054] Invocation finished in 1h50m43.009230563s with status FAILED