[09:44:34.614] New invocation is queued and will start shortly
[09:44:35.715] Starting the invocation (attempt 1)
[09:44:35.747] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:44:35.747] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:44:35.747] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021394620366621104",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"294903a70f492a8164515f22a51889d27615bc52\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9021394620366621104",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/294903a70f492a8164515f22a51889d27615bc52",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/294903a70f492a8164515f22a51889d27615bc52",
"gitiles_ref:refs/heads/master"
]
}
[09:44:37.137] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612691075893774",
"id": "8855964558362791632",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"294903a70f492a8164515f22a51889d27615bc52\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612691076927467",
"tags": [
"build_address:luci.chromium.ci/win-official/1556",
"builder:win-official",
"buildset:commit/git/294903a70f492a8164515f22a51889d27615bc52",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/294903a70f492a8164515f22a51889d27615bc52",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021394620366621104",
"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/8855964558362791632/+/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": "1612691076927575",
"url": "https://ci.chromium.org/b/8855964558362791632",
"utcnow_ts": "1612691077130046"
}
}
[09:44:37.138] Task URL: https://ci.chromium.org/b/8855964558362791632
[09:44:37.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:2:0) after 1m0s
[09:45:11.362] Received PubSub notification, asking Buildbucket for the build status
[09:45:11.422] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:37.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:2:0)
[09:45:37.211] Timer tick, asking Buildbucket for the build status
[09:45:37.281] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:37.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:4:0) after 1m0s
[09:46:37.297] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:4:0)
[09:46:37.297] Timer tick, asking Buildbucket for the build status
[09:46:37.529] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:37.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:5:0) after 1m0s
[09:47:37.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:5:0)
[09:47:37.545] Timer tick, asking Buildbucket for the build status
[09:47:37.817] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:37.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:6:0) after 1m0s
[09:48:37.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:6:0)
[09:48:37.887] Timer tick, asking Buildbucket for the build status
[09:48:38.002] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:38.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:7:0) after 1m0s
[09:49:38.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:7:0)
[09:49:38.021] Timer tick, asking Buildbucket for the build status
[09:49:38.118] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:38.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:8:0) after 1m0s
[09:50:38.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:8:0)
[09:50:38.187] Timer tick, asking Buildbucket for the build status
[09:50:38.403] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:38.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:9:0) after 1m0s
[09:51:38.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:9:0)
[09:51:38.413] Timer tick, asking Buildbucket for the build status
[09:51:38.498] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:38.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:10:0) after 1m0s
[09:52:38.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:10:0)
[09:52:38.544] Timer tick, asking Buildbucket for the build status
[09:52:38.630] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:38.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:11:0) after 1m0s
[09:53:38.670] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:11:0)
[09:53:38.670] Timer tick, asking Buildbucket for the build status
[09:53:38.760] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:38.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:12:0) after 1m0s
[09:54:38.790] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:12:0)
[09:54:38.790] Timer tick, asking Buildbucket for the build status
[09:54:38.917] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:38.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:13:0) after 1m0s
[09:55:38.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:13:0)
[09:55:38.958] Timer tick, asking Buildbucket for the build status
[09:55:39.242] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:39.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:14:0) after 1m0s
[09:56:39.280] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:14:0)
[09:56:39.280] Timer tick, asking Buildbucket for the build status
[09:56:39.498] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:39.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:15:0) after 1m0s
[09:57:39.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:15:0)
[09:57:39.505] Timer tick, asking Buildbucket for the build status
[09:57:39.574] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:39.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:16:0) after 1m0s
[09:58:39.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:16:0)
[09:58:39.600] Timer tick, asking Buildbucket for the build status
[09:58:39.681] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:39.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:17:0) after 1m0s
[09:59:39.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:17:0)
[09:59:39.760] Timer tick, asking Buildbucket for the build status
[09:59:39.880] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:39.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:18:0) after 1m0s
[10:00:39.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:18:0)
[10:00:39.903] Timer tick, asking Buildbucket for the build status
[10:00:39.968] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:39.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:19:0) after 1m0s
[10:01:39.988] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:19:0)
[10:01:39.988] Timer tick, asking Buildbucket for the build status
[10:01:40.056] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:40.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:20:0) after 1m0s
[10:02:40.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:20:0)
[10:02:40.086] Timer tick, asking Buildbucket for the build status
[10:02:40.207] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:40.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:21:0) after 1m0s
[10:03:40.278] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:21:0)
[10:03:40.278] Timer tick, asking Buildbucket for the build status
[10:03:40.350] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:40.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:22:0) after 1m0s
[10:04:40.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:22:0)
[10:04:40.444] Timer tick, asking Buildbucket for the build status
[10:04:40.570] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:40.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:23:0) after 1m0s
[10:05:40.702] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:23:0)
[10:05:40.702] Timer tick, asking Buildbucket for the build status
[10:05:42.234] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:42.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:24:0) after 1m0s
[10:06:42.255] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:24:0)
[10:06:42.255] Timer tick, asking Buildbucket for the build status
[10:06:42.389] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:42.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:25:0) after 1m0s
[10:07:42.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:25:0)
[10:07:42.406] Timer tick, asking Buildbucket for the build status
[10:07:42.460] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:42.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:26:0) after 1m0s
[10:08:42.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:26:0)
[10:08:42.529] Timer tick, asking Buildbucket for the build status
[10:08:42.633] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:42.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:27:0) after 1m0s
[10:09:42.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:27:0)
[10:09:42.652] Timer tick, asking Buildbucket for the build status
[10:09:42.788] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:42.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:28:0) after 1m0s
[10:10:42.824] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:28:0)
[10:10:42.824] Timer tick, asking Buildbucket for the build status
[10:10:42.895] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:42.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:29:0) after 1m0s
[10:11:42.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:29:0)
[10:11:42.921] Timer tick, asking Buildbucket for the build status
[10:11:43.156] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:43.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:30:0) after 1m0s
[10:12:43.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:30:0)
[10:12:43.174] Timer tick, asking Buildbucket for the build status
[10:12:43.232] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:43.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:31:0) after 1m0s
[10:13:43.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:31:0)
[10:13:43.295] Timer tick, asking Buildbucket for the build status
[10:13:43.406] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:43.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:32:0) after 1m0s
[10:14:43.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:32:0)
[10:14:43.477] Timer tick, asking Buildbucket for the build status
[10:14:43.580] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:43.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:33:0) after 1m0s
[10:15:43.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:33:0)
[10:15:43.669] Timer tick, asking Buildbucket for the build status
[10:15:43.734] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:43.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:34:0) after 1m0s
[10:16:43.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:34:0)
[10:16:43.932] Timer tick, asking Buildbucket for the build status
[10:16:43.991] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:43.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:35:0) after 1m0s
[10:17:44.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:35:0)
[10:17:44.174] Timer tick, asking Buildbucket for the build status
[10:17:44.252] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:44.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:36:0) after 1m0s
[10:18:44.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:36:0)
[10:18:44.259] Timer tick, asking Buildbucket for the build status
[10:18:44.354] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:44.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:37:0) after 1m0s
[10:19:44.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:37:0)
[10:19:44.520] Timer tick, asking Buildbucket for the build status
[10:19:44.817] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:44.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:38:0) after 1m0s
[10:20:44.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:38:0)
[10:20:44.993] Timer tick, asking Buildbucket for the build status
[10:20:45.109] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:45.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:39:0) after 1m0s
[10:21:45.184] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:39:0)
[10:21:45.184] Timer tick, asking Buildbucket for the build status
[10:21:45.303] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:45.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:40:0) after 1m0s
[10:22:45.476] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:40:0)
[10:22:45.476] Timer tick, asking Buildbucket for the build status
[10:22:45.536] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:45.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:41:0) after 1m0s
[10:23:45.725] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:41:0)
[10:23:45.725] Timer tick, asking Buildbucket for the build status
[10:23:45.937] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:45.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:42:0) after 1m0s
[10:24:45.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:42:0)
[10:24:45.987] Timer tick, asking Buildbucket for the build status
[10:24:46.133] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:46.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:43:0) after 1m0s
[10:25:46.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:43:0)
[10:25:46.157] Timer tick, asking Buildbucket for the build status
[10:25:46.238] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:46.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:44:0) after 1m0s
[10:26:46.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:44:0)
[10:26:46.269] Timer tick, asking Buildbucket for the build status
[10:26:46.332] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:46.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:45:0) after 1m0s
[10:27:46.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:45:0)
[10:27:46.366] Timer tick, asking Buildbucket for the build status
[10:27:46.510] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:46.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:46:0) after 1m0s
[10:28:46.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:46:0)
[10:28:46.580] Timer tick, asking Buildbucket for the build status
[10:28:46.751] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:46.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:47:0) after 1m0s
[10:29:46.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:47:0)
[10:29:46.802] Timer tick, asking Buildbucket for the build status
[10:29:46.943] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:46.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:48:0) after 1m0s
[10:30:47.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:48:0)
[10:30:47.012] Timer tick, asking Buildbucket for the build status
[10:30:47.075] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:47.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:49:0) after 1m0s
[10:31:47.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:49:0)
[10:31:47.125] Timer tick, asking Buildbucket for the build status
[10:31:47.209] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:47.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:50:0) after 1m0s
[10:32:47.321] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:50:0)
[10:32:47.321] Timer tick, asking Buildbucket for the build status
[10:32:47.413] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:47.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:51:0) after 1m0s
[10:33:47.558] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:51:0)
[10:33:47.558] Timer tick, asking Buildbucket for the build status
[10:33:47.698] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:47.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:52:0) after 1m0s
[10:34:47.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:52:0)
[10:34:47.784] Timer tick, asking Buildbucket for the build status
[10:34:47.881] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:47.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:53:0) after 1m0s
[10:35:47.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:53:0)
[10:35:47.971] Timer tick, asking Buildbucket for the build status
[10:35:48.111] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:48.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:54:0) after 1m0s
[10:36:48.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:54:0)
[10:36:48.183] Timer tick, asking Buildbucket for the build status
[10:36:48.395] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:48.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:55:0) after 1m0s
[10:37:48.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:55:0)
[10:37:48.416] Timer tick, asking Buildbucket for the build status
[10:37:48.510] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:48.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:56:0) after 1m0s
[10:38:48.576] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:56:0)
[10:38:48.576] Timer tick, asking Buildbucket for the build status
[10:38:48.661] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:48.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:57:0) after 1m0s
[10:39:48.706] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:57:0)
[10:39:48.706] Timer tick, asking Buildbucket for the build status
[10:39:48.957] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:48.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:58:0) after 1m0s
[10:40:49.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:58:0)
[10:40:49.041] Timer tick, asking Buildbucket for the build status
[10:40:49.377] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:49.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:59:0) after 1m0s
[10:41:49.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:59:0)
[10:41:49.401] Timer tick, asking Buildbucket for the build status
[10:41:49.658] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:49.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:60:0) after 1m0s
[10:42:49.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:60:0)
[10:42:49.696] Timer tick, asking Buildbucket for the build status
[10:42:49.750] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:49.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:61:0) after 1m0s
[10:43:49.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:61:0)
[10:43:49.794] Timer tick, asking Buildbucket for the build status
[10:43:49.860] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:49.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:62:0) after 1m0s
[10:44:49.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:62:0)
[10:44:49.933] Timer tick, asking Buildbucket for the build status
[10:44:50.039] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:50.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:63:0) after 1m0s
[10:45:50.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:63:0)
[10:45:50.112] Timer tick, asking Buildbucket for the build status
[10:45:50.171] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:50.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:64:0) after 1m0s
[10:46:50.181] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:64:0)
[10:46:50.181] Timer tick, asking Buildbucket for the build status
[10:46:50.245] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:50.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:65:0) after 1m0s
[10:47:50.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:65:0)
[10:47:50.266] Timer tick, asking Buildbucket for the build status
[10:47:50.319] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:50.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:66:0) after 1m0s
[10:48:50.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:66:0)
[10:48:50.288] Timer tick, asking Buildbucket for the build status
[10:48:50.350] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:50.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:67:0) after 1m0s
[10:49:50.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:67:0)
[10:49:50.340] Timer tick, asking Buildbucket for the build status
[10:49:50.509] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:50.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:68:0) after 1m0s
[10:50:50.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:68:0)
[10:50:50.580] Timer tick, asking Buildbucket for the build status
[10:50:50.679] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:50.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:69:0) after 1m0s
[10:51:50.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:69:0)
[10:51:50.704] Timer tick, asking Buildbucket for the build status
[10:51:50.915] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:50.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:70:0) after 1m0s
[10:52:51.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:70:0)
[10:52:51.005] Timer tick, asking Buildbucket for the build status
[10:52:51.080] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:51.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:71:0) after 1m0s
[10:53:51.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:71:0)
[10:53:51.130] Timer tick, asking Buildbucket for the build status
[10:53:51.208] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:51.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:72:0) after 1m0s
[10:54:51.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:72:0)
[10:54:51.277] Timer tick, asking Buildbucket for the build status
[10:54:51.377] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:51.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:73:0) after 1m0s
[10:55:51.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:73:0)
[10:55:51.439] Timer tick, asking Buildbucket for the build status
[10:55:51.501] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:51.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:74:0) after 1m0s
[10:56:51.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:74:0)
[10:56:51.521] Timer tick, asking Buildbucket for the build status
[10:56:51.992] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:51.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:75:0) after 1m0s
[10:57:52.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:75:0)
[10:57:52.023] Timer tick, asking Buildbucket for the build status
[10:57:52.816] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:52.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:76:0) after 1m0s
[10:58:52.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:76:0)
[10:58:52.898] Timer tick, asking Buildbucket for the build status
[10:58:52.957] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:52.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:77:0) after 1m0s
[10:59:53.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:77:0)
[10:59:53.037] Timer tick, asking Buildbucket for the build status
[10:59:53.311] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:53.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:78:0) after 1m0s
[11:00:53.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:78:0)
[11:00:53.348] Timer tick, asking Buildbucket for the build status
[11:00:53.426] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:53.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:79:0) after 1m0s
[11:01:53.446] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:79:0)
[11:01:53.446] Timer tick, asking Buildbucket for the build status
[11:01:53.557] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:53.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:80:0) after 1m0s
[11:02:53.576] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:80:0)
[11:02:53.576] Timer tick, asking Buildbucket for the build status
[11:02:53.632] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:53.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:81:0) after 1m0s
[11:03:53.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:81:0)
[11:03:53.656] Timer tick, asking Buildbucket for the build status
[11:03:53.715] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:53.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:82:0) after 1m0s
[11:04:53.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:82:0)
[11:04:53.738] Timer tick, asking Buildbucket for the build status
[11:04:56.387] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:56.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:83:0) after 1m0s
[11:05:56.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:83:0)
[11:05:56.410] Timer tick, asking Buildbucket for the build status
[11:05:56.495] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:56.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:84:0) after 1m0s
[11:06:56.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:84:0)
[11:06:56.517] Timer tick, asking Buildbucket for the build status
[11:06:56.628] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:56.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:85:0) after 1m0s
[11:07:56.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:85:0)
[11:07:56.652] Timer tick, asking Buildbucket for the build status
[11:07:56.711] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:56.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:86:0) after 1m0s
[11:08:56.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:86:0)
[11:08:56.787] Timer tick, asking Buildbucket for the build status
[11:08:56.848] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:56.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:87:0) after 1m0s
[11:09:56.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:87:0)
[11:09:56.847] Timer tick, asking Buildbucket for the build status
[11:09:56.952] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:56.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:88:0) after 1m0s
[11:10:56.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:88:0)
[11:10:56.974] Timer tick, asking Buildbucket for the build status
[11:10:57.106] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:57.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:89:0) after 1m0s
[11:11:57.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:89:0)
[11:11:57.140] Timer tick, asking Buildbucket for the build status
[11:11:57.427] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:57.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:90:0) after 1m0s
[11:12:57.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:90:0)
[11:12:57.451] Timer tick, asking Buildbucket for the build status
[11:12:57.622] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:57.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:91:0) after 1m0s
[11:13:57.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:91:0)
[11:13:57.629] Timer tick, asking Buildbucket for the build status
[11:13:57.689] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:57.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:92:0) after 1m0s
[11:14:57.812] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:92:0)
[11:14:57.812] Timer tick, asking Buildbucket for the build status
[11:14:58.242] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:58.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:93:0) after 1m0s
[11:15:58.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:93:0)
[11:15:58.243] Timer tick, asking Buildbucket for the build status
[11:15:58.331] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:58.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:94:0) after 1m0s
[11:16:58.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:94:0)
[11:16:58.483] Timer tick, asking Buildbucket for the build status
[11:17:04.872] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:04.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:95:0) after 1m0s
[11:18:04.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:95:0)
[11:18:04.945] Timer tick, asking Buildbucket for the build status
[11:18:05.038] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:05.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:96:0) after 1m0s
[11:19:05.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:96:0)
[11:19:05.077] Timer tick, asking Buildbucket for the build status
[11:19:05.238] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:05.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:97:0) after 1m0s
[11:20:05.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:97:0)
[11:20:05.258] Timer tick, asking Buildbucket for the build status
[11:20:05.402] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:05.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:98:0) after 1m0s
[11:21:05.812] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:98:0)
[11:21:05.812] Timer tick, asking Buildbucket for the build status
[11:21:06.018] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:06.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:99:0) after 1m0s
[11:22:06.248] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:99:0)
[11:22:06.248] Timer tick, asking Buildbucket for the build status
[11:22:06.333] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:06.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:100:0) after 1m0s
[11:23:06.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:100:0)
[11:23:06.514] Timer tick, asking Buildbucket for the build status
[11:23:06.735] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:06.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:101:0) after 1m0s
[11:24:07.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:101:0)
[11:24:07.034] Timer tick, asking Buildbucket for the build status
[11:24:07.099] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:07.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:102:0) after 1m0s
[11:25:07.220] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:102:0)
[11:25:07.220] Timer tick, asking Buildbucket for the build status
[11:25:07.414] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:07.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:103:0) after 1m0s
[11:26:07.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:103:0)
[11:26:07.438] Timer tick, asking Buildbucket for the build status
[11:26:07.550] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:07.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:104:0) after 1m0s
[11:27:07.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:104:0)
[11:27:07.640] Timer tick, asking Buildbucket for the build status
[11:27:07.803] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:07.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:105:0) after 1m0s
[11:28:07.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:105:0)
[11:28:07.993] Timer tick, asking Buildbucket for the build status
[11:28:08.247] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:08.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:106:0) after 1m0s
[11:29:08.273] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:106:0)
[11:29:08.273] Timer tick, asking Buildbucket for the build status
[11:29:08.333] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:08.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:107:0) after 1m0s
[11:30:08.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:107:0)
[11:30:08.470] Timer tick, asking Buildbucket for the build status
[11:30:08.567] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:08.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:108:0) after 1m0s
[11:31:09.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:108:0)
[11:31:09.107] Timer tick, asking Buildbucket for the build status
[11:31:09.187] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:09.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:109:0) after 1m0s
[11:32:09.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:109:0)
[11:32:09.272] Timer tick, asking Buildbucket for the build status
[11:32:09.340] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:09.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:110:0) after 1m0s
[11:33:09.472] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:110:0)
[11:33:09.472] Timer tick, asking Buildbucket for the build status
[11:33:09.664] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:09.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:111:0) after 1m0s
[11:34:09.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:111:0)
[11:34:09.802] Timer tick, asking Buildbucket for the build status
[11:34:10.024] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:10.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:112:0) after 1m0s
[11:35:10.095] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:112:0)
[11:35:10.095] Timer tick, asking Buildbucket for the build status
[11:35:10.202] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:10.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:113:0) after 1m0s
[11:36:10.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:113:0)
[11:36:10.863] Timer tick, asking Buildbucket for the build status
[11:36:10.925] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:10.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:114:0) after 1m0s
[11:37:10.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:114:0)
[11:37:10.941] Timer tick, asking Buildbucket for the build status
[11:37:11.182] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:11.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:115:0) after 1m0s
[11:38:11.200] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:115:0)
[11:38:11.200] Timer tick, asking Buildbucket for the build status
[11:38:11.273] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:11.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:116:0) after 1m0s
[11:39:11.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:116:0)
[11:39:11.313] Timer tick, asking Buildbucket for the build status
[11:39:11.388] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:11.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:117:0) after 1m0s
[11:40:11.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:117:0)
[11:40:11.442] Timer tick, asking Buildbucket for the build status
[11:40:11.537] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:11.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:118:0) after 1m0s
[11:41:11.559] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:118:0)
[11:41:11.559] Timer tick, asking Buildbucket for the build status
[11:41:11.654] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:11.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:119:0) after 1m0s
[11:42:11.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:119:0)
[11:42:11.708] Timer tick, asking Buildbucket for the build status
[11:42:11.822] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:11.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:120:0) after 1m0s
[11:43:11.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:120:0)
[11:43:11.833] Timer tick, asking Buildbucket for the build status
[11:43:12.027] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:12.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:121:0) after 1m0s
[11:44:12.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:121:0)
[11:44:12.289] Timer tick, asking Buildbucket for the build status
[11:44:12.345] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:12.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:122:0) after 1m0s
[11:45:12.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:122:0)
[11:45:12.484] Timer tick, asking Buildbucket for the build status
[11:45:12.597] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:12.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:123:0) after 1m0s
[11:46:12.718] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:123:0)
[11:46:12.718] Timer tick, asking Buildbucket for the build status
[11:46:13.048] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:13.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:124:0) after 1m0s
[11:47:13.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:124:0)
[11:47:13.155] Timer tick, asking Buildbucket for the build status
[11:47:13.217] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:13.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:125:0) after 1m0s
[11:48:13.291] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:125:0)
[11:48:13.291] Timer tick, asking Buildbucket for the build status
[11:48:13.374] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:13.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:126:0) after 1m0s
[11:49:13.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:126:0)
[11:49:13.499] Timer tick, asking Buildbucket for the build status
[11:49:13.666] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:13.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:127:0) after 1m0s
[11:50:13.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:127:0)
[11:50:13.809] Timer tick, asking Buildbucket for the build status
[11:50:14.039] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:14.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:128:0) after 1m0s
[11:51:14.085] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:128:0)
[11:51:14.085] Timer tick, asking Buildbucket for the build status
[11:51:14.455] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:14.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:129:0) after 1m0s
[11:52:14.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:129:0)
[11:52:14.484] Timer tick, asking Buildbucket for the build status
[11:52:14.559] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:14.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:130:0) after 1m0s
[11:53:14.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:130:0)
[11:53:14.816] Timer tick, asking Buildbucket for the build status
[11:53:15.421] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:15.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:131:0) after 1m0s
[11:54:15.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:131:0)
[11:54:15.515] Timer tick, asking Buildbucket for the build status
[11:54:15.574] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:15.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:132:0) after 1m0s
[11:55:15.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:132:0)
[11:55:15.739] Timer tick, asking Buildbucket for the build status
[11:55:15.830] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:15.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:133:0) after 1m0s
[11:56:15.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:133:0)
[11:56:15.982] Timer tick, asking Buildbucket for the build status
[11:56:16.054] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:16.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:134:0) after 1m0s
[11:57:16.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:134:0)
[11:57:16.122] Timer tick, asking Buildbucket for the build status
[11:57:16.229] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:16.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:135:0) after 1m0s
[11:58:16.329] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:135:0)
[11:58:16.329] Timer tick, asking Buildbucket for the build status
[11:58:16.612] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:16.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:136:0) after 1m0s
[11:59:16.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:136:0)
[11:59:16.732] Timer tick, asking Buildbucket for the build status
[11:59:16.824] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:16.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:137:0) after 1m0s
[12:00:16.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:137:0)
[12:00:16.855] Timer tick, asking Buildbucket for the build status
[12:00:17.069] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:17.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:138:0) after 1m0s
[12:01:17.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:138:0)
[12:01:17.152] Timer tick, asking Buildbucket for the build status
[12:01:17.564] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:17.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:139:0) after 1m0s
[12:02:17.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:139:0)
[12:02:17.697] Timer tick, asking Buildbucket for the build status
[12:02:17.783] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:17.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:140:0) after 1m0s
[12:03:17.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:140:0)
[12:03:17.879] Timer tick, asking Buildbucket for the build status
[12:03:17.942] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:17.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:141:0) after 1m0s
[12:04:18.119] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:141:0)
[12:04:18.119] Timer tick, asking Buildbucket for the build status
[12:04:18.992] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:18.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:142:0) after 1m0s
[12:05:19.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:142:0)
[12:05:19.193] Timer tick, asking Buildbucket for the build status
[12:05:19.320] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:19.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:143:0) after 1m0s
[12:06:19.557] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:143:0)
[12:06:19.557] Timer tick, asking Buildbucket for the build status
[12:06:19.649] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:19.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:144:0) after 1m0s
[12:07:19.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:144:0)
[12:07:19.822] Timer tick, asking Buildbucket for the build status
[12:07:19.875] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:19.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:145:0) after 1m0s
[12:08:20.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:145:0)
[12:08:20.013] Timer tick, asking Buildbucket for the build status
[12:08:20.079] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:20.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:146:0) after 1m0s
[12:09:20.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:146:0)
[12:09:20.176] Timer tick, asking Buildbucket for the build status
[12:09:20.406] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:20.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:147:0) after 1m0s
[12:10:20.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:147:0)
[12:10:20.626] Timer tick, asking Buildbucket for the build status
[12:10:20.730] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:20.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:148:0) after 1m0s
[12:11:20.806] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:148:0)
[12:11:20.806] Timer tick, asking Buildbucket for the build status
[12:11:21.232] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:21.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:149:0) after 1m0s
[12:12:21.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:149:0)
[12:12:21.403] Timer tick, asking Buildbucket for the build status
[12:12:22.652] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:22.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:150:0) after 1m0s
[12:13:22.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:150:0)
[12:13:22.784] Timer tick, asking Buildbucket for the build status
[12:13:22.857] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:22.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:151:0) after 1m0s
[12:14:22.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:151:0)
[12:14:22.989] Timer tick, asking Buildbucket for the build status
[12:14:23.381] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:23.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:152:0) after 1m0s
[12:15:23.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:152:0)
[12:15:23.400] Timer tick, asking Buildbucket for the build status
[12:15:23.613] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:23.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:153:0) after 1m0s
[12:16:23.724] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:153:0)
[12:16:23.724] Timer tick, asking Buildbucket for the build status
[12:16:23.911] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:23.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:154:0) after 1m0s
[12:17:23.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:154:0)
[12:17:23.985] Timer tick, asking Buildbucket for the build status
[12:17:24.117] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:24.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:155:0) after 1m0s
[12:18:24.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:155:0)
[12:18:24.151] Timer tick, asking Buildbucket for the build status
[12:18:24.503] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:24.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:156:0) after 1m0s
[12:19:24.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:156:0)
[12:19:24.526] Timer tick, asking Buildbucket for the build status
[12:19:24.735] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:24.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:157:0) after 1m0s
[12:20:24.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:157:0)
[12:20:24.758] Timer tick, asking Buildbucket for the build status
[12:20:25.306] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:25.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:158:0) after 1m0s
[12:21:25.504] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:158:0)
[12:21:25.504] Timer tick, asking Buildbucket for the build status
[12:21:25.590] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:25.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:159:0) after 1m0s
[12:22:25.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:159:0)
[12:22:25.696] Timer tick, asking Buildbucket for the build status
[12:22:25.833] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:25.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:160:0) after 1m0s
[12:23:25.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:160:0)
[12:23:25.844] Timer tick, asking Buildbucket for the build status
[12:23:25.910] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:25.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:161:0) after 1m0s
[12:24:25.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:161:0)
[12:24:25.958] Timer tick, asking Buildbucket for the build status
[12:24:26.018] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:26.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:162:0) after 1m0s
[12:25:26.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:162:0)
[12:25:26.052] Timer tick, asking Buildbucket for the build status
[12:25:26.233] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:26.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:163:0) after 1m0s
[12:26:27.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:163:0)
[12:26:27.005] Timer tick, asking Buildbucket for the build status
[12:26:27.144] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:27.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:164:0) after 1m0s
[12:27:27.129] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:164:0)
[12:27:27.129] Timer tick, asking Buildbucket for the build status
[12:27:27.202] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:27.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:165:0) after 1m0s
[12:28:27.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:165:0)
[12:28:27.228] Timer tick, asking Buildbucket for the build status
[12:28:27.301] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:27.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:166:0) after 1m0s
[12:29:27.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:166:0)
[12:29:27.283] Timer tick, asking Buildbucket for the build status
[12:29:27.360] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:27.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:167:0) after 1m0s
[12:30:27.382] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:167:0)
[12:30:27.382] Timer tick, asking Buildbucket for the build status
[12:30:27.468] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:27.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:168:0) after 1m0s
[12:31:27.523] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:168:0)
[12:31:27.523] Timer tick, asking Buildbucket for the build status
[12:31:27.718] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:27.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:169:0) after 1m0s
[12:32:27.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:169:0)
[12:32:27.811] Timer tick, asking Buildbucket for the build status
[12:32:27.901] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:27.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:170:0) after 1m0s
[12:33:27.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:170:0)
[12:33:27.971] Timer tick, asking Buildbucket for the build status
[12:33:28.710] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:28.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:171:0) after 1m0s
[12:34:28.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:171:0)
[12:34:28.763] Timer tick, asking Buildbucket for the build status
[12:34:28.830] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:28.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:172:0) after 1m0s
[12:35:28.916] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:172:0)
[12:35:28.916] Timer tick, asking Buildbucket for the build status
[12:35:29.497] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:29.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:173:0) after 1m0s
[12:36:29.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:173:0)
[12:36:29.527] Timer tick, asking Buildbucket for the build status
[12:36:29.590] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:29.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:174:0) after 1m0s
[12:37:29.581] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:174:0)
[12:37:29.581] Timer tick, asking Buildbucket for the build status
[12:37:29.665] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:29.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:175:0) after 1m0s
[12:38:29.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:175:0)
[12:38:29.861] Timer tick, asking Buildbucket for the build status
[12:38:30.250] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:30.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:176:0) after 1m0s
[12:39:30.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:176:0)
[12:39:30.517] Timer tick, asking Buildbucket for the build status
[12:39:30.782] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:30.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:177:0) after 1m0s
[12:40:30.979] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:177:0)
[12:40:30.979] Timer tick, asking Buildbucket for the build status
[12:40:31.307] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:31.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:178:0) after 1m0s
[12:41:31.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:178:0)
[12:41:31.387] Timer tick, asking Buildbucket for the build status
[12:41:31.475] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:31.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:179:0) after 1m0s
[12:42:31.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:179:0)
[12:42:31.502] Timer tick, asking Buildbucket for the build status
[12:42:31.619] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:31.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:180:0) after 1m0s
[12:43:31.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:180:0)
[12:43:31.738] Timer tick, asking Buildbucket for the build status
[12:43:31.809] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:31.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:181:0) after 1m0s
[12:44:31.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:181:0)
[12:44:31.955] Timer tick, asking Buildbucket for the build status
[12:44:32.086] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:32.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:182:0) after 1m0s
[12:45:32.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:182:0)
[12:45:32.122] Timer tick, asking Buildbucket for the build status
[12:45:32.308] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:32.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:183:0) after 1m0s
[12:46:32.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:183:0)
[12:46:32.395] Timer tick, asking Buildbucket for the build status
[12:46:32.479] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:32.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:184:0) after 1m0s
[12:47:32.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:184:0)
[12:47:32.575] Timer tick, asking Buildbucket for the build status
[12:47:32.637] Build 8855964558362791632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:32.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021394620366621104:185:0) after 1m0s
[12:47:45.809] Received PubSub notification, asking Buildbucket for the build status
[12:47:45.873] Build 8855964558362791632: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:47:45.873] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612702064757099",
"created_by": "project:chromium",
"created_ts": "1612691075893774",
"id": "8855964558362791632",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"294903a70f492a8164515f22a51889d27615bc52\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"294903a70f492a8164515f22a51889d27615bc52\", \"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-9-qwrj\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1556, \"got_angle_revision\": \"580961fbb01984d2d9b2f382609f9fc0c44e6b82\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"294903a70f492a8164515f22a51889d27615bc52\", \"got_revision_cp\": \"refs/heads/master@{#851527}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"462378addab907ab7452d38baccb27fe2ef7289a\", \"got_v8_revision_cp\": \"refs/heads/9.0.138@{#1}\", \"got_webrtc_revision\": \"d42413a4b4636422ba5ea66ba40e7d24fb64f9b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33183}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"294903a70f492a8164515f22a51889d27615bc52\"}, \"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-9-qwrj\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5676-c76598c\"], \"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": "1612691110731188",
"status": "COMPLETED",
"status_changed_ts": "1612702065266503",
"tags": [
"build_address:luci.chromium.ci/win-official/1556",
"builder:win-official",
"buildset:commit/git/294903a70f492a8164515f22a51889d27615bc52",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/294903a70f492a8164515f22a51889d27615bc52",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021394620366621104",
"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/8855964558362791632/+/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:5194b281569b6110",
"user_agent:luci-scheduler"
],
"updated_ts": "1612702065297070",
"url": "https://ci.chromium.org/b/8855964558362791632",
"utcnow_ts": "1612702065865985"
}
}
[12:47:45.873] Invocation finished in 3h3m11.274991505s with status SUCCEEDED