[16:41:00.316] New invocation is queued and will start shortly
[16:41:03.073] Starting the invocation (attempt 1)
[16:41:03.111] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:41:03.111] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:41:03.111] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035320353787156224",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9edf481245b455cf923ed17a838082e37cee70fa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9035320353787156224",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/9edf481245b455cf923ed17a838082e37cee70fa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9edf481245b455cf923ed17a838082e37cee70fa",
"gitiles_ref:refs/heads/master"
]
}
[16:41:06.433] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599410464198351",
"id": "8869890289051149440",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9edf481245b455cf923ed17a838082e37cee70fa\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599410466045625",
"tags": [
"build_address:luci.chromium.ci/win-official/522",
"builder:win-official",
"buildset:commit/git/9edf481245b455cf923ed17a838082e37cee70fa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9edf481245b455cf923ed17a838082e37cee70fa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035320353787156224",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8869890289051149440/+/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": "1599410466045805",
"url": "https://ci.chromium.org/b/8869890289051149440",
"utcnow_ts": "1599410466404475"
}
}
[16:41:06.433] Task URL: https://ci.chromium.org/b/8869890289051149440
[16:41:06.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:2:0) after 1m0s
[16:42:06.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:2:0)
[16:42:06.768] Timer tick, asking Buildbucket for the build status
[16:42:07.061] Build 8869890289051149440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:42:07.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:3:0) after 1m0s
[16:42:36.518] Received PubSub notification, asking Buildbucket for the build status
[16:42:36.912] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:07.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:3:0)
[16:43:07.483] Timer tick, asking Buildbucket for the build status
[16:43:07.628] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:07.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:5:0) after 1m0s
[16:44:07.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:5:0)
[16:44:07.672] Timer tick, asking Buildbucket for the build status
[16:44:07.761] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:07.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:6:0) after 1m0s
[16:45:07.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:6:0)
[16:45:07.783] Timer tick, asking Buildbucket for the build status
[16:45:07.938] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:07.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:7:0) after 1m0s
[16:46:08.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:7:0)
[16:46:08.124] Timer tick, asking Buildbucket for the build status
[16:46:08.269] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:08.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:8:0) after 1m0s
[16:47:08.625] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:8:0)
[16:47:08.625] Timer tick, asking Buildbucket for the build status
[16:47:08.813] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:08.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:9:0) after 1m0s
[16:48:08.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:9:0)
[16:48:08.942] Timer tick, asking Buildbucket for the build status
[16:48:09.076] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:09.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:10:0) after 1m0s
[16:49:09.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:10:0)
[16:49:09.160] Timer tick, asking Buildbucket for the build status
[16:49:09.358] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:09.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:11:0) after 1m0s
[16:50:09.474] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:11:0)
[16:50:09.474] Timer tick, asking Buildbucket for the build status
[16:50:09.577] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:09.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:12:0) after 1m0s
[16:51:09.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:12:0)
[16:51:09.807] Timer tick, asking Buildbucket for the build status
[16:51:10.316] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:10.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:13:0) after 1m0s
[16:52:10.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:13:0)
[16:52:10.501] Timer tick, asking Buildbucket for the build status
[16:52:11.033] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:11.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:14:0) after 1m0s
[16:53:11.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:14:0)
[16:53:11.245] Timer tick, asking Buildbucket for the build status
[16:53:11.418] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:11.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:15:0) after 1m0s
[16:54:11.468] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:15:0)
[16:54:11.468] Timer tick, asking Buildbucket for the build status
[16:54:11.593] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:11.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:16:0) after 1m0s
[16:55:11.778] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:16:0)
[16:55:11.778] Timer tick, asking Buildbucket for the build status
[16:55:11.923] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:11.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:17:0) after 1m0s
[16:56:11.943] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:17:0)
[16:56:11.943] Timer tick, asking Buildbucket for the build status
[16:56:12.070] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:12.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:18:0) after 1m0s
[16:57:12.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:18:0)
[16:57:12.102] Timer tick, asking Buildbucket for the build status
[16:57:12.303] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:12.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:19:0) after 1m0s
[16:58:12.376] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:19:0)
[16:58:12.376] Timer tick, asking Buildbucket for the build status
[16:58:12.516] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:12.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:20:0) after 1m0s
[16:59:12.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:20:0)
[16:59:12.626] Timer tick, asking Buildbucket for the build status
[16:59:12.713] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:12.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:21:0) after 1m0s
[17:00:12.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:21:0)
[17:00:12.747] Timer tick, asking Buildbucket for the build status
[17:00:12.878] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:12.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:22:0) after 1m0s
[17:01:13.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:22:0)
[17:01:13.025] Timer tick, asking Buildbucket for the build status
[17:01:13.190] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:13.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:23:0) after 1m0s
[17:02:13.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:23:0)
[17:02:13.282] Timer tick, asking Buildbucket for the build status
[17:02:13.438] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:13.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:24:0) after 1m0s
[17:03:13.576] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:24:0)
[17:03:13.576] Timer tick, asking Buildbucket for the build status
[17:03:13.673] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:13.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:25:0) after 1m0s
[17:04:13.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:25:0)
[17:04:13.758] Timer tick, asking Buildbucket for the build status
[17:04:14.082] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:14.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:26:0) after 1m0s
[17:05:14.119] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:26:0)
[17:05:14.119] Timer tick, asking Buildbucket for the build status
[17:05:14.577] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:14.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:27:0) after 1m0s
[17:06:14.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:27:0)
[17:06:14.676] Timer tick, asking Buildbucket for the build status
[17:06:14.874] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:14.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:28:0) after 1m0s
[17:07:14.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:28:0)
[17:07:14.974] Timer tick, asking Buildbucket for the build status
[17:07:15.220] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:15.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:29:0) after 1m0s
[17:08:15.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:29:0)
[17:08:15.293] Timer tick, asking Buildbucket for the build status
[17:08:15.362] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:15.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:30:0) after 1m0s
[17:09:15.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:30:0)
[17:09:15.404] Timer tick, asking Buildbucket for the build status
[17:09:15.482] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:15.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:31:0) after 1m0s
[17:10:15.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:31:0)
[17:10:15.653] Timer tick, asking Buildbucket for the build status
[17:10:15.890] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:15.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:32:0) after 1m0s
[17:11:16.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:32:0)
[17:11:16.033] Timer tick, asking Buildbucket for the build status
[17:11:16.389] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:16.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:33:0) after 1m0s
[17:12:16.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:33:0)
[17:12:16.419] Timer tick, asking Buildbucket for the build status
[17:12:16.486] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:16.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:34:0) after 1m0s
[17:13:16.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:34:0)
[17:13:16.534] Timer tick, asking Buildbucket for the build status
[17:13:16.733] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:16.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:35:0) after 1m0s
[17:14:16.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:35:0)
[17:14:16.760] Timer tick, asking Buildbucket for the build status
[17:14:16.839] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:16.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:36:0) after 1m0s
[17:15:16.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:36:0)
[17:15:16.961] Timer tick, asking Buildbucket for the build status
[17:15:17.106] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:17.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:37:0) after 1m0s
[17:16:17.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:37:0)
[17:16:17.187] Timer tick, asking Buildbucket for the build status
[17:16:17.344] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:17.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:38:0) after 1m0s
[17:17:17.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:38:0)
[17:17:17.383] Timer tick, asking Buildbucket for the build status
[17:17:17.533] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:17.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:39:0) after 1m0s
[17:18:17.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:39:0)
[17:18:17.863] Timer tick, asking Buildbucket for the build status
[17:18:18.025] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:18.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:40:0) after 1m0s
[17:19:18.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:40:0)
[17:19:18.107] Timer tick, asking Buildbucket for the build status
[17:19:18.173] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:18.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:41:0) after 1m0s
[17:20:18.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:41:0)
[17:20:18.292] Timer tick, asking Buildbucket for the build status
[17:20:18.373] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:18.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:42:0) after 1m0s
[17:21:18.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:42:0)
[17:21:18.563] Timer tick, asking Buildbucket for the build status
[17:21:18.679] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:18.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:43:0) after 1m0s
[17:22:19.030] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:43:0)
[17:22:19.033] Timer tick, asking Buildbucket for the build status
[17:22:19.550] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:19.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:44:0) after 1m0s
[17:23:19.654] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:44:0)
[17:23:19.654] Timer tick, asking Buildbucket for the build status
[17:23:19.904] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:19.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:45:0) after 1m0s
[17:24:19.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:45:0)
[17:24:19.930] Timer tick, asking Buildbucket for the build status
[17:24:20.055] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:20.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:46:0) after 1m0s
[17:25:20.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:46:0)
[17:25:20.168] Timer tick, asking Buildbucket for the build status
[17:25:20.477] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:20.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:47:0) after 1m0s
[17:26:20.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:47:0)
[17:26:20.547] Timer tick, asking Buildbucket for the build status
[17:26:20.670] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:20.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:48:0) after 1m0s
[17:27:20.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:48:0)
[17:27:20.750] Timer tick, asking Buildbucket for the build status
[17:27:20.941] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:20.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:49:0) after 1m0s
[17:28:20.967] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:49:0)
[17:28:20.967] Timer tick, asking Buildbucket for the build status
[17:28:21.057] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:21.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:50:0) after 1m0s
[17:29:21.128] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:50:0)
[17:29:21.128] Timer tick, asking Buildbucket for the build status
[17:29:21.284] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:21.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:51:0) after 1m0s
[17:30:21.344] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:51:0)
[17:30:21.344] Timer tick, asking Buildbucket for the build status
[17:30:21.576] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:21.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:52:0) after 1m0s
[17:31:21.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:52:0)
[17:31:21.599] Timer tick, asking Buildbucket for the build status
[17:31:21.734] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:21.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:53:0) after 1m0s
[17:32:21.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:53:0)
[17:32:21.809] Timer tick, asking Buildbucket for the build status
[17:32:21.901] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:21.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:54:0) after 1m0s
[17:33:21.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:54:0)
[17:33:21.899] Timer tick, asking Buildbucket for the build status
[17:33:21.967] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:21.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:55:0) after 1m0s
[17:34:22.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:55:0)
[17:34:22.005] Timer tick, asking Buildbucket for the build status
[17:34:22.159] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:22.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:56:0) after 1m0s
[17:35:22.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:56:0)
[17:35:22.142] Timer tick, asking Buildbucket for the build status
[17:35:22.401] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:22.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:57:0) after 1m0s
[17:36:22.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:57:0)
[17:36:22.451] Timer tick, asking Buildbucket for the build status
[17:36:24.013] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:24.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:58:0) after 1m0s
[17:37:24.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:58:0)
[17:37:24.056] Timer tick, asking Buildbucket for the build status
[17:37:24.180] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:24.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:59:0) after 1m0s
[17:38:24.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:59:0)
[17:38:24.452] Timer tick, asking Buildbucket for the build status
[17:38:24.979] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:24.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:60:0) after 1m0s
[17:39:25.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:60:0)
[17:39:25.077] Timer tick, asking Buildbucket for the build status
[17:39:25.145] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:25.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:61:0) after 1m0s
[17:40:25.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:61:0)
[17:40:25.176] Timer tick, asking Buildbucket for the build status
[17:40:25.243] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:25.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:62:0) after 1m0s
[17:41:25.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:62:0)
[17:41:25.264] Timer tick, asking Buildbucket for the build status
[17:41:25.433] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:25.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:63:0) after 1m0s
[17:42:25.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:63:0)
[17:42:25.455] Timer tick, asking Buildbucket for the build status
[17:42:25.533] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:25.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:64:0) after 1m0s
[17:43:25.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:64:0)
[17:43:25.529] Timer tick, asking Buildbucket for the build status
[17:43:25.592] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:25.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:65:0) after 1m0s
[17:44:25.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:65:0)
[17:44:25.616] Timer tick, asking Buildbucket for the build status
[17:44:25.813] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:25.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:66:0) after 1m0s
[17:45:25.840] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:66:0)
[17:45:25.840] Timer tick, asking Buildbucket for the build status
[17:45:25.918] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:25.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:67:0) after 1m0s
[17:46:25.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:67:0)
[17:46:25.940] Timer tick, asking Buildbucket for the build status
[17:46:26.101] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:26.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:68:0) after 1m0s
[17:47:26.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:68:0)
[17:47:26.125] Timer tick, asking Buildbucket for the build status
[17:47:26.211] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:26.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:69:0) after 1m0s
[17:48:26.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:69:0)
[17:48:26.477] Timer tick, asking Buildbucket for the build status
[17:48:26.598] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:26.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:70:0) after 1m0s
[17:49:26.631] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:70:0)
[17:49:26.632] Timer tick, asking Buildbucket for the build status
[17:49:26.742] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:26.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:71:0) after 1m0s
[17:50:26.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:71:0)
[17:50:26.777] Timer tick, asking Buildbucket for the build status
[17:50:26.854] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:26.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:72:0) after 1m0s
[17:51:26.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:72:0)
[17:51:26.891] Timer tick, asking Buildbucket for the build status
[17:51:27.042] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:27.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:73:0) after 1m0s
[17:52:27.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:73:0)
[17:52:27.065] Timer tick, asking Buildbucket for the build status
[17:52:27.165] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:27.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:74:0) after 1m0s
[17:53:27.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:74:0)
[17:53:27.159] Timer tick, asking Buildbucket for the build status
[17:53:27.225] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:27.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:75:0) after 1m0s
[17:54:27.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:75:0)
[17:54:27.356] Timer tick, asking Buildbucket for the build status
[17:54:27.419] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:27.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:76:0) after 1m0s
[17:55:27.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:76:0)
[17:55:27.529] Timer tick, asking Buildbucket for the build status
[17:55:27.592] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:27.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:77:0) after 1m0s
[17:56:27.617] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:77:0)
[17:56:27.617] Timer tick, asking Buildbucket for the build status
[17:56:27.723] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:27.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:78:0) after 1m0s
[17:57:27.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:78:0)
[17:57:27.763] Timer tick, asking Buildbucket for the build status
[17:57:27.837] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:27.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:79:0) after 1m0s
[17:58:28.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:79:0)
[17:58:28.219] Timer tick, asking Buildbucket for the build status
[17:58:28.469] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:28.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:80:0) after 1m0s
[17:59:28.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:80:0)
[17:59:28.481] Timer tick, asking Buildbucket for the build status
[17:59:28.598] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:28.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:81:0) after 1m0s
[18:00:28.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:81:0)
[18:00:28.688] Timer tick, asking Buildbucket for the build status
[18:00:28.868] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:28.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:82:0) after 1m0s
[18:01:28.992] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:82:0)
[18:01:28.992] Timer tick, asking Buildbucket for the build status
[18:01:29.108] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:29.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:83:0) after 1m0s
[18:02:29.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:83:0)
[18:02:29.192] Timer tick, asking Buildbucket for the build status
[18:02:29.499] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:29.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:84:0) after 1m0s
[18:03:29.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:84:0)
[18:03:29.534] Timer tick, asking Buildbucket for the build status
[18:03:29.633] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:29.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:85:0) after 1m0s
[18:04:29.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:85:0)
[18:04:29.665] Timer tick, asking Buildbucket for the build status
[18:04:29.830] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:29.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:86:0) after 1m0s
[18:05:29.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:86:0)
[18:05:29.861] Timer tick, asking Buildbucket for the build status
[18:05:30.008] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:30.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:87:0) after 1m0s
[18:06:30.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:87:0)
[18:06:30.033] Timer tick, asking Buildbucket for the build status
[18:06:30.323] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:30.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:88:0) after 1m0s
[18:07:30.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:88:0)
[18:07:30.345] Timer tick, asking Buildbucket for the build status
[18:07:30.596] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:30.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:89:0) after 1m0s
[18:08:30.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:89:0)
[18:08:30.615] Timer tick, asking Buildbucket for the build status
[18:08:30.680] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:30.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:90:0) after 1m0s
[18:09:30.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:90:0)
[18:09:30.827] Timer tick, asking Buildbucket for the build status
[18:09:30.913] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:30.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:91:0) after 1m0s
[18:10:30.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:91:0)
[18:10:30.991] Timer tick, asking Buildbucket for the build status
[18:10:31.417] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:31.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:92:0) after 1m0s
[18:11:31.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:92:0)
[18:11:31.452] Timer tick, asking Buildbucket for the build status
[18:11:31.913] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:31.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:93:0) after 1m0s
[18:12:31.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:93:0)
[18:12:31.949] Timer tick, asking Buildbucket for the build status
[18:12:32.019] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:32.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:94:0) after 1m0s
[18:13:32.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:94:0)
[18:13:32.059] Timer tick, asking Buildbucket for the build status
[18:13:32.138] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:32.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:95:0) after 1m0s
[18:14:32.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:95:0)
[18:14:32.164] Timer tick, asking Buildbucket for the build status
[18:14:32.303] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:32.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:96:0) after 1m0s
[18:15:32.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:96:0)
[18:15:32.327] Timer tick, asking Buildbucket for the build status
[18:15:32.387] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:32.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:97:0) after 1m0s
[18:16:32.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:97:0)
[18:16:32.499] Timer tick, asking Buildbucket for the build status
[18:16:32.699] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:32.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:98:0) after 1m0s
[18:17:32.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:98:0)
[18:17:32.777] Timer tick, asking Buildbucket for the build status
[18:17:32.929] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:32.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:99:0) after 1m0s
[18:18:33.014] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:99:0)
[18:18:33.014] Timer tick, asking Buildbucket for the build status
[18:18:33.069] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:33.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:100:0) after 1m0s
[18:19:33.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:100:0)
[18:19:33.105] Timer tick, asking Buildbucket for the build status
[18:19:33.329] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:33.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:101:0) after 1m0s
[18:20:33.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:101:0)
[18:20:33.364] Timer tick, asking Buildbucket for the build status
[18:20:33.443] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:33.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:102:0) after 1m0s
[18:21:33.494] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:102:0)
[18:21:33.494] Timer tick, asking Buildbucket for the build status
[18:21:33.652] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:33.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:103:0) after 1m0s
[18:22:33.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:103:0)
[18:22:33.844] Timer tick, asking Buildbucket for the build status
[18:22:33.906] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:33.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:104:0) after 1m0s
[18:23:34.373] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:104:0)
[18:23:34.373] Timer tick, asking Buildbucket for the build status
[18:23:34.439] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:34.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:105:0) after 1m0s
[18:24:34.914] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:105:0)
[18:24:34.918] Timer tick, asking Buildbucket for the build status
[18:24:35.449] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:35.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:106:0) after 1m0s
[18:25:35.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:106:0)
[18:25:35.533] Timer tick, asking Buildbucket for the build status
[18:25:35.616] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:35.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:107:0) after 1m0s
[18:26:35.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:107:0)
[18:26:35.695] Timer tick, asking Buildbucket for the build status
[18:26:35.809] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:35.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:108:0) after 1m0s
[18:27:35.831] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:108:0)
[18:27:35.831] Timer tick, asking Buildbucket for the build status
[18:27:35.899] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:35.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:109:0) after 1m0s
[18:28:35.995] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:109:0)
[18:28:35.995] Timer tick, asking Buildbucket for the build status
[18:28:36.229] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:36.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:110:0) after 1m0s
[18:29:36.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:110:0)
[18:29:36.307] Timer tick, asking Buildbucket for the build status
[18:29:36.370] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:36.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:111:0) after 1m0s
[18:30:36.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:111:0)
[18:30:36.369] Timer tick, asking Buildbucket for the build status
[18:30:36.502] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:36.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:112:0) after 1m0s
[18:31:36.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:112:0)
[18:31:36.618] Timer tick, asking Buildbucket for the build status
[18:31:36.766] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:36.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:113:0) after 1m0s
[18:32:36.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:113:0)
[18:32:36.970] Timer tick, asking Buildbucket for the build status
[18:32:37.046] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:37.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:114:0) after 1m0s
[18:33:37.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:114:0)
[18:33:37.393] Timer tick, asking Buildbucket for the build status
[18:33:37.506] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:37.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:115:0) after 1m0s
[18:34:37.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:115:0)
[18:34:37.645] Timer tick, asking Buildbucket for the build status
[18:34:37.739] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:37.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:116:0) after 1m0s
[18:35:38.002] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:116:0)
[18:35:38.002] Timer tick, asking Buildbucket for the build status
[18:35:38.131] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:38.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:117:0) after 1m0s
[18:36:38.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:117:0)
[18:36:38.369] Timer tick, asking Buildbucket for the build status
[18:36:38.505] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:38.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:118:0) after 1m0s
[18:37:38.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:118:0)
[18:37:38.529] Timer tick, asking Buildbucket for the build status
[18:37:38.633] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:38.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:119:0) after 1m0s
[18:38:38.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:119:0)
[18:38:38.701] Timer tick, asking Buildbucket for the build status
[18:38:38.839] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:38.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:120:0) after 1m0s
[18:39:38.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:120:0)
[18:39:38.864] Timer tick, asking Buildbucket for the build status
[18:39:38.935] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:38.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:121:0) after 1m0s
[18:40:38.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:121:0)
[18:40:38.974] Timer tick, asking Buildbucket for the build status
[18:40:39.072] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:39.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:122:0) after 1m0s
[18:41:39.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:122:0)
[18:41:39.101] Timer tick, asking Buildbucket for the build status
[18:41:39.406] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:39.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:123:0) after 1m0s
[18:42:39.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:123:0)
[18:42:39.470] Timer tick, asking Buildbucket for the build status
[18:42:39.602] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:39.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:124:0) after 1m0s
[18:43:39.624] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:124:0)
[18:43:39.624] Timer tick, asking Buildbucket for the build status
[18:43:39.677] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:39.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:125:0) after 1m0s
[18:44:39.698] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:125:0)
[18:44:39.698] Timer tick, asking Buildbucket for the build status
[18:44:39.762] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:39.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:126:0) after 1m0s
[18:45:39.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:126:0)
[18:45:39.880] Timer tick, asking Buildbucket for the build status
[18:45:40.106] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:40.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:127:0) after 1m0s
[18:46:40.170] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:127:0)
[18:46:40.170] Timer tick, asking Buildbucket for the build status
[18:46:40.350] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:40.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:128:0) after 1m0s
[18:47:40.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:128:0)
[18:47:40.337] Timer tick, asking Buildbucket for the build status
[18:47:40.508] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:40.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:129:0) after 1m0s
[18:48:40.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:129:0)
[18:48:40.571] Timer tick, asking Buildbucket for the build status
[18:48:40.985] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:40.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:130:0) after 1m0s
[18:49:41.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:130:0)
[18:49:41.140] Timer tick, asking Buildbucket for the build status
[18:49:41.452] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:41.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:131:0) after 1m0s
[18:50:41.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:131:0)
[18:50:41.505] Timer tick, asking Buildbucket for the build status
[18:50:41.685] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:41.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:132:0) after 1m0s
[18:51:41.710] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:132:0)
[18:51:41.710] Timer tick, asking Buildbucket for the build status
[18:51:41.845] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:41.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:133:0) after 1m0s
[18:52:42.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:133:0)
[18:52:42.078] Timer tick, asking Buildbucket for the build status
[18:52:42.164] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:42.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:134:0) after 1m0s
[18:53:42.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:134:0)
[18:53:42.160] Timer tick, asking Buildbucket for the build status
[18:53:42.226] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:42.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:135:0) after 1m0s
[18:54:42.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:135:0)
[18:54:42.289] Timer tick, asking Buildbucket for the build status
[18:54:42.370] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:42.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:136:0) after 1m0s
[18:55:42.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:136:0)
[18:55:42.517] Timer tick, asking Buildbucket for the build status
[18:55:42.625] Build 8869890289051149440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:42.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035320353787156224:137:0) after 1m0s
[18:56:37.395] Received PubSub notification, asking Buildbucket for the build status
[18:56:37.636] Build 8869890289051149440: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:56:37.636] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599418596251624",
"created_by": "project:chromium",
"created_ts": "1599410464198351",
"id": "8869890289051149440",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9edf481245b455cf923ed17a838082e37cee70fa\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"9edf481245b455cf923ed17a838082e37cee70fa\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-7-se10\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 522, \"got_angle_revision\": \"72f6b99c29f28bd7461f383e9b7223a8f670b7cc\", \"got_dawn_revision\": \"62442aceb0fb8af0c1edd07b5f18b4ec181b8fbf\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"9edf481245b455cf923ed17a838082e37cee70fa\", \"got_revision_cp\": \"refs/heads/master@{#804631}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"e63aa953ddfd935a022090b3ef221438a328d1a1\", \"got_v8_revision\": \"ef9e56c6d3efce74216a967f3774a5c8bc8c142a\", \"got_v8_revision_cp\": \"refs/heads/8.7.74@{#1}\", \"got_webrtc_revision\": \"d46db9f1523ae45909b4a6fdc90a140443068bc6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32036}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9edf481245b455cf923ed17a838082e37cee70fa\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-7-se10\"], \"image\": [\"chrome-win10-1703-20081700-d9392568d25\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5507-6e8d655\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1599410553406717",
"status": "COMPLETED",
"status_changed_ts": "1599418596733299",
"tags": [
"build_address:luci.chromium.ci/win-official/522",
"builder:win-official",
"buildset:commit/git/9edf481245b455cf923ed17a838082e37cee70fa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9edf481245b455cf923ed17a838082e37cee70fa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035320353787156224",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8869890289051149440/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4e7d1c796a1cbc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1599418596743549",
"url": "https://ci.chromium.org/b/8869890289051149440",
"utcnow_ts": "1599418597616498"
}
}
[18:56:37.636] Invocation finished in 2h15m37.33823878s with status SUCCEEDED