[11:27:32.301] New invocation is queued and will start shortly
[11:27:33.681] Starting the invocation (attempt 1)
[11:27:33.747] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:27:33.747] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:27:33.747] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037152014750231040",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d3f2565851bccc3434367bebf60110f9c65338e5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9037152014750231040",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/d3f2565851bccc3434367bebf60110f9c65338e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d3f2565851bccc3434367bebf60110f9c65338e5",
"gitiles_ref:refs/heads/master"
]
}
[11:27:36.041] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597663654387433",
"id": "8871721951896139760",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d3f2565851bccc3434367bebf60110f9c65338e5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597663655577119",
"tags": [
"build_address:luci.chromium.ci/win-official/365",
"builder:win-official",
"buildset:commit/git/d3f2565851bccc3434367bebf60110f9c65338e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d3f2565851bccc3434367bebf60110f9c65338e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037152014750231040",
"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/8871721951896139760/+/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": "1597663655577782",
"url": "https://ci.chromium.org/b/8871721951896139760",
"utcnow_ts": "1597663656019207"
}
}
[11:27:36.042] Task URL: https://ci.chromium.org/b/8871721951896139760
[11:27:36.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:2:0) after 1m0s
[11:27:40.487] Received PubSub notification, asking Buildbucket for the build status
[11:27:40.587] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:36.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:2:0)
[11:28:36.135] Timer tick, asking Buildbucket for the build status
[11:28:36.326] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:36.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:4:0) after 1m0s
[11:29:36.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:4:0)
[11:29:36.354] Timer tick, asking Buildbucket for the build status
[11:29:36.517] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:36.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:5:0) after 1m0s
[11:30:36.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:5:0)
[11:30:36.603] Timer tick, asking Buildbucket for the build status
[11:30:36.789] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:36.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:6:0) after 1m0s
[11:31:36.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:6:0)
[11:31:36.817] Timer tick, asking Buildbucket for the build status
[11:31:37.069] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:37.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:7:0) after 1m0s
[11:32:37.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:7:0)
[11:32:37.076] Timer tick, asking Buildbucket for the build status
[11:32:37.466] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:37.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:8:0) after 1m0s
[11:33:37.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:8:0)
[11:33:37.490] Timer tick, asking Buildbucket for the build status
[11:33:37.954] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:37.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:9:0) after 1m0s
[11:34:38.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:9:0)
[11:34:38.042] Timer tick, asking Buildbucket for the build status
[11:34:38.187] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:38.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:10:0) after 1m0s
[11:35:38.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:10:0)
[11:35:38.223] Timer tick, asking Buildbucket for the build status
[11:35:38.412] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:38.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:11:0) after 1m0s
[11:36:38.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:11:0)
[11:36:38.447] Timer tick, asking Buildbucket for the build status
[11:36:39.214] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:39.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:12:0) after 1m0s
[11:37:39.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:12:0)
[11:37:39.233] Timer tick, asking Buildbucket for the build status
[11:37:39.363] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:39.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:13:0) after 1m0s
[11:38:39.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:13:0)
[11:38:39.387] Timer tick, asking Buildbucket for the build status
[11:38:39.765] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:39.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:14:0) after 1m0s
[11:39:39.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:14:0)
[11:39:39.825] Timer tick, asking Buildbucket for the build status
[11:39:39.897] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:39.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:15:0) after 1m0s
[11:40:39.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:15:0)
[11:40:39.925] Timer tick, asking Buildbucket for the build status
[11:40:39.982] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:39.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:16:0) after 1m0s
[11:41:40.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:16:0)
[11:41:40.012] Timer tick, asking Buildbucket for the build status
[11:41:40.106] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:40.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:17:0) after 1m0s
[11:42:40.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:17:0)
[11:42:40.165] Timer tick, asking Buildbucket for the build status
[11:42:40.349] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:40.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:18:0) after 1m0s
[11:43:40.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:18:0)
[11:43:40.402] Timer tick, asking Buildbucket for the build status
[11:43:40.596] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:40.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:19:0) after 1m0s
[11:44:40.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:19:0)
[11:44:40.626] Timer tick, asking Buildbucket for the build status
[11:44:40.733] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:40.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:20:0) after 1m0s
[11:45:40.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:20:0)
[11:45:40.779] Timer tick, asking Buildbucket for the build status
[11:45:40.870] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:40.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:21:0) after 1m0s
[11:46:40.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:21:0)
[11:46:40.908] Timer tick, asking Buildbucket for the build status
[11:46:41.259] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:41.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:22:0) after 1m0s
[11:47:41.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:22:0)
[11:47:41.279] Timer tick, asking Buildbucket for the build status
[11:47:41.503] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:41.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:23:0) after 1m0s
[11:48:41.558] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:23:0)
[11:48:41.558] Timer tick, asking Buildbucket for the build status
[11:48:41.660] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:41.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:24:0) after 1m0s
[11:49:41.706] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:24:0)
[11:49:41.706] Timer tick, asking Buildbucket for the build status
[11:49:42.413] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:42.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:25:0) after 1m0s
[11:50:42.479] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:25:0)
[11:50:42.479] Timer tick, asking Buildbucket for the build status
[11:50:42.685] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:42.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:26:0) after 1m0s
[11:51:42.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:26:0)
[11:51:42.736] Timer tick, asking Buildbucket for the build status
[11:51:42.830] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:42.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:27:0) after 1m0s
[11:52:42.830] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:27:0)
[11:52:42.830] Timer tick, asking Buildbucket for the build status
[11:52:42.974] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:42.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:28:0) after 1m0s
[11:53:43.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:28:0)
[11:53:43.013] Timer tick, asking Buildbucket for the build status
[11:53:43.102] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:43.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:29:0) after 1m0s
[11:54:43.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:29:0)
[11:54:43.164] Timer tick, asking Buildbucket for the build status
[11:54:43.267] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:43.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:30:0) after 1m0s
[11:55:43.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:30:0)
[11:55:43.244] Timer tick, asking Buildbucket for the build status
[11:55:43.483] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:43.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:31:0) after 1m0s
[11:56:43.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:31:0)
[11:56:43.509] Timer tick, asking Buildbucket for the build status
[11:56:43.864] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:43.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:32:0) after 1m0s
[11:57:43.916] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:32:0)
[11:57:43.916] Timer tick, asking Buildbucket for the build status
[11:57:44.077] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:44.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:33:0) after 1m0s
[11:58:44.121] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:33:0)
[11:58:44.121] Timer tick, asking Buildbucket for the build status
[11:58:44.205] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:44.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:34:0) after 1m0s
[11:59:44.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:34:0)
[11:59:44.286] Timer tick, asking Buildbucket for the build status
[11:59:44.496] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:44.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:35:0) after 1m0s
[12:00:44.578] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:35:0)
[12:00:44.578] Timer tick, asking Buildbucket for the build status
[12:00:44.666] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:44.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:36:0) after 1m0s
[12:01:44.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:36:0)
[12:01:44.694] Timer tick, asking Buildbucket for the build status
[12:01:44.785] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:44.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:37:0) after 1m0s
[12:02:44.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:37:0)
[12:02:44.829] Timer tick, asking Buildbucket for the build status
[12:02:45.633] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:45.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:38:0) after 1m0s
[12:03:45.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:38:0)
[12:03:45.712] Timer tick, asking Buildbucket for the build status
[12:03:46.052] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:46.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:39:0) after 1m0s
[12:04:46.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:39:0)
[12:04:46.123] Timer tick, asking Buildbucket for the build status
[12:04:46.211] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:46.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:40:0) after 1m0s
[12:05:46.252] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:40:0)
[12:05:46.252] Timer tick, asking Buildbucket for the build status
[12:05:46.367] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:46.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:41:0) after 1m0s
[12:06:46.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:41:0)
[12:06:46.403] Timer tick, asking Buildbucket for the build status
[12:06:46.536] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:46.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:42:0) after 1m0s
[12:07:46.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:42:0)
[12:07:46.574] Timer tick, asking Buildbucket for the build status
[12:07:46.710] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:46.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:43:0) after 1m0s
[12:08:46.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:43:0)
[12:08:46.785] Timer tick, asking Buildbucket for the build status
[12:08:46.861] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:46.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:44:0) after 1m0s
[12:09:46.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:44:0)
[12:09:46.867] Timer tick, asking Buildbucket for the build status
[12:09:46.946] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:46.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:45:0) after 1m0s
[12:10:46.975] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:45:0)
[12:10:46.976] Timer tick, asking Buildbucket for the build status
[12:10:47.064] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:47.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:46:0) after 1m0s
[12:11:47.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:46:0)
[12:11:47.102] Timer tick, asking Buildbucket for the build status
[12:11:47.193] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:47.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:47:0) after 1m0s
[12:12:47.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:47:0)
[12:12:47.312] Timer tick, asking Buildbucket for the build status
[12:12:47.449] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:47.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:48:0) after 1m0s
[12:13:47.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:48:0)
[12:13:47.482] Timer tick, asking Buildbucket for the build status
[12:13:47.574] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:47.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:49:0) after 1m0s
[12:14:47.661] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:49:0)
[12:14:47.661] Timer tick, asking Buildbucket for the build status
[12:14:47.814] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:47.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:50:0) after 1m0s
[12:15:47.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:50:0)
[12:15:47.817] Timer tick, asking Buildbucket for the build status
[12:15:48.009] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:48.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:51:0) after 1m0s
[12:16:48.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:51:0)
[12:16:48.065] Timer tick, asking Buildbucket for the build status
[12:16:48.279] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:48.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:52:0) after 1m0s
[12:17:48.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:52:0)
[12:17:48.302] Timer tick, asking Buildbucket for the build status
[12:17:49.013] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:49.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:53:0) after 1m0s
[12:18:49.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:53:0)
[12:18:49.038] Timer tick, asking Buildbucket for the build status
[12:18:49.090] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:49.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:54:0) after 1m0s
[12:19:49.121] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:54:0)
[12:19:49.121] Timer tick, asking Buildbucket for the build status
[12:19:49.216] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:49.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:55:0) after 1m0s
[12:20:49.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:55:0)
[12:20:49.259] Timer tick, asking Buildbucket for the build status
[12:20:49.351] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:49.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:56:0) after 1m0s
[12:21:49.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:56:0)
[12:21:49.397] Timer tick, asking Buildbucket for the build status
[12:21:49.485] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:49.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:57:0) after 1m0s
[12:22:49.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:57:0)
[12:22:49.530] Timer tick, asking Buildbucket for the build status
[12:22:49.680] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:49.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:58:0) after 1m0s
[12:23:49.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:58:0)
[12:23:49.730] Timer tick, asking Buildbucket for the build status
[12:23:49.820] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:49.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:59:0) after 1m0s
[12:24:49.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:59:0)
[12:24:49.881] Timer tick, asking Buildbucket for the build status
[12:24:49.955] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:49.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:60:0) after 1m0s
[12:25:50.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:60:0)
[12:25:50.102] Timer tick, asking Buildbucket for the build status
[12:25:50.482] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:50.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:61:0) after 1m0s
[12:26:50.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:61:0)
[12:26:50.539] Timer tick, asking Buildbucket for the build status
[12:26:50.742] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:50.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:62:0) after 1m0s
[12:27:50.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:62:0)
[12:27:50.920] Timer tick, asking Buildbucket for the build status
[12:27:51.146] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:51.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:63:0) after 1m0s
[12:28:51.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:63:0)
[12:28:51.264] Timer tick, asking Buildbucket for the build status
[12:28:51.336] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:51.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:64:0) after 1m0s
[12:29:51.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:64:0)
[12:29:51.429] Timer tick, asking Buildbucket for the build status
[12:29:51.505] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:51.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:65:0) after 1m0s
[12:30:51.540] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:65:0)
[12:30:51.540] Timer tick, asking Buildbucket for the build status
[12:30:51.633] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:51.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:66:0) after 1m0s
[12:31:51.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:66:0)
[12:31:51.850] Timer tick, asking Buildbucket for the build status
[12:31:52.004] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:52.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:67:0) after 1m0s
[12:32:52.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:67:0)
[12:32:52.026] Timer tick, asking Buildbucket for the build status
[12:32:52.258] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:52.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:68:0) after 1m0s
[12:33:52.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:68:0)
[12:33:52.342] Timer tick, asking Buildbucket for the build status
[12:33:52.405] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:52.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:69:0) after 1m0s
[12:34:52.462] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:69:0)
[12:34:52.462] Timer tick, asking Buildbucket for the build status
[12:34:52.642] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:52.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:70:0) after 1m0s
[12:35:52.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:70:0)
[12:35:52.647] Timer tick, asking Buildbucket for the build status
[12:35:52.717] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:52.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:71:0) after 1m0s
[12:36:52.801] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:71:0)
[12:36:52.801] Timer tick, asking Buildbucket for the build status
[12:36:53.076] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:53.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:72:0) after 1m0s
[12:37:53.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:72:0)
[12:37:53.173] Timer tick, asking Buildbucket for the build status
[12:37:53.581] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:53.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:73:0) after 1m0s
[12:38:53.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:73:0)
[12:38:53.620] Timer tick, asking Buildbucket for the build status
[12:38:53.884] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:53.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:74:0) after 1m0s
[12:39:54.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:74:0)
[12:39:54.023] Timer tick, asking Buildbucket for the build status
[12:39:54.212] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:54.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:75:0) after 1m0s
[12:40:54.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:75:0)
[12:40:54.266] Timer tick, asking Buildbucket for the build status
[12:40:54.555] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:54.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:76:0) after 1m0s
[12:41:54.606] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:76:0)
[12:41:54.606] Timer tick, asking Buildbucket for the build status
[12:41:54.695] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:54.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:77:0) after 1m0s
[12:42:54.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:77:0)
[12:42:54.720] Timer tick, asking Buildbucket for the build status
[12:42:54.882] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:54.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:78:0) after 1m0s
[12:43:54.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:78:0)
[12:43:54.899] Timer tick, asking Buildbucket for the build status
[12:43:55.015] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:55.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:79:0) after 1m0s
[12:44:55.010] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:79:0)
[12:44:55.010] Timer tick, asking Buildbucket for the build status
[12:44:55.443] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:55.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:80:0) after 1m0s
[12:45:55.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:80:0)
[12:45:55.484] Timer tick, asking Buildbucket for the build status
[12:45:55.585] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:55.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:81:0) after 1m0s
[12:46:55.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:81:0)
[12:46:55.647] Timer tick, asking Buildbucket for the build status
[12:46:55.801] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:55.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:82:0) after 1m0s
[12:47:56.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:82:0)
[12:47:56.105] Timer tick, asking Buildbucket for the build status
[12:47:56.275] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:56.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:83:0) after 1m0s
[12:48:56.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:83:0)
[12:48:56.388] Timer tick, asking Buildbucket for the build status
[12:48:56.710] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:56.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:84:0) after 1m0s
[12:49:56.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:84:0)
[12:49:56.735] Timer tick, asking Buildbucket for the build status
[12:49:56.965] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:56.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:85:0) after 1m0s
[12:50:57.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:85:0)
[12:50:57.052] Timer tick, asking Buildbucket for the build status
[12:50:57.162] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:57.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:86:0) after 1m0s
[12:51:57.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:86:0)
[12:51:57.235] Timer tick, asking Buildbucket for the build status
[12:51:57.306] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:57.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:87:0) after 1m0s
[12:52:57.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:87:0)
[12:52:57.394] Timer tick, asking Buildbucket for the build status
[12:52:57.498] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:57.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:88:0) after 1m0s
[12:53:57.495] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:88:0)
[12:53:57.495] Timer tick, asking Buildbucket for the build status
[12:53:57.580] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:57.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:89:0) after 1m0s
[12:54:57.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:89:0)
[12:54:57.640] Timer tick, asking Buildbucket for the build status
[12:54:57.700] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:57.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:90:0) after 1m0s
[12:55:57.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:90:0)
[12:55:57.737] Timer tick, asking Buildbucket for the build status
[12:55:57.808] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:57.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:91:0) after 1m0s
[12:56:57.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:91:0)
[12:56:57.832] Timer tick, asking Buildbucket for the build status
[12:56:57.932] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:57.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:92:0) after 1m0s
[12:57:58.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:92:0)
[12:57:58.003] Timer tick, asking Buildbucket for the build status
[12:57:58.156] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:58.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:93:0) after 1m0s
[12:58:58.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:93:0)
[12:58:58.207] Timer tick, asking Buildbucket for the build status
[12:58:58.309] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:58.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:94:0) after 1m0s
[12:59:58.360] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:94:0)
[12:59:58.360] Timer tick, asking Buildbucket for the build status
[12:59:58.484] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:58.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:95:0) after 1m0s
[13:00:58.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:95:0)
[13:00:58.548] Timer tick, asking Buildbucket for the build status
[13:00:58.633] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:58.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:96:0) after 1m0s
[13:01:58.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:96:0)
[13:01:58.742] Timer tick, asking Buildbucket for the build status
[13:01:58.820] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:58.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:97:0) after 1m0s
[13:02:58.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:97:0)
[13:02:58.994] Timer tick, asking Buildbucket for the build status
[13:02:59.141] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:59.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:98:0) after 1m0s
[13:03:59.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:98:0)
[13:03:59.223] Timer tick, asking Buildbucket for the build status
[13:03:59.349] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:59.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:99:0) after 1m0s
[13:04:59.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:99:0)
[13:04:59.800] Timer tick, asking Buildbucket for the build status
[13:04:59.880] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:59.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:100:0) after 1m0s
[13:06:00.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:100:0)
[13:06:00.059] Timer tick, asking Buildbucket for the build status
[13:06:00.272] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:00.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:101:0) after 1m0s
[13:12:34.859] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:101:0)
[13:12:34.859] Timer tick, asking Buildbucket for the build status
[13:12:35.019] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:35.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:102:0) after 1m0s
[13:13:35.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:102:0)
[13:13:35.042] Timer tick, asking Buildbucket for the build status
[13:13:35.176] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:35.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:103:0) after 1m0s
[13:14:35.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:103:0)
[13:14:35.210] Timer tick, asking Buildbucket for the build status
[13:14:35.315] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:35.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:104:0) after 1m0s
[13:15:35.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:104:0)
[13:15:35.347] Timer tick, asking Buildbucket for the build status
[13:15:35.445] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:35.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:105:0) after 1m0s
[13:16:35.489] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:105:0)
[13:16:35.489] Timer tick, asking Buildbucket for the build status
[13:16:35.653] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:35.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:106:0) after 1m0s
[13:17:35.714] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:106:0)
[13:17:35.714] Timer tick, asking Buildbucket for the build status
[13:17:35.796] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:35.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:107:0) after 1m0s
[13:18:35.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:107:0)
[13:18:35.872] Timer tick, asking Buildbucket for the build status
[13:18:36.023] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:36.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:108:0) after 1m0s
[13:19:36.061] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:108:0)
[13:19:36.061] Timer tick, asking Buildbucket for the build status
[13:19:36.377] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:36.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:109:0) after 1m0s
[13:20:36.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:109:0)
[13:20:36.510] Timer tick, asking Buildbucket for the build status
[13:20:36.607] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:36.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:110:0) after 1m0s
[13:21:36.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:110:0)
[13:21:36.669] Timer tick, asking Buildbucket for the build status
[13:21:36.935] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:36.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:111:0) after 1m0s
[13:22:37.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:111:0)
[13:22:37.107] Timer tick, asking Buildbucket for the build status
[13:22:37.214] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:37.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:112:0) after 1m0s
[13:23:37.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:112:0)
[13:23:37.229] Timer tick, asking Buildbucket for the build status
[13:23:37.379] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:37.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:113:0) after 1m0s
[13:24:37.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:113:0)
[13:24:37.419] Timer tick, asking Buildbucket for the build status
[13:24:37.501] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:37.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:114:0) after 1m0s
[13:25:37.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:114:0)
[13:25:37.621] Timer tick, asking Buildbucket for the build status
[13:25:37.680] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:37.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:115:0) after 1m0s
[13:26:37.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:115:0)
[13:26:37.794] Timer tick, asking Buildbucket for the build status
[13:26:38.841] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:38.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:116:0) after 1m0s
[13:27:38.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:116:0)
[13:27:38.896] Timer tick, asking Buildbucket for the build status
[13:27:39.035] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:39.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:117:0) after 1m0s
[13:28:39.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:117:0)
[13:28:39.057] Timer tick, asking Buildbucket for the build status
[13:28:39.224] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:39.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:118:0) after 1m0s
[13:29:39.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:118:0)
[13:29:39.310] Timer tick, asking Buildbucket for the build status
[13:29:39.592] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:39.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:119:0) after 1m0s
[13:30:39.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:119:0)
[13:30:39.630] Timer tick, asking Buildbucket for the build status
[13:30:39.729] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:39.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:120:0) after 1m0s
[13:31:39.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:120:0)
[13:31:39.814] Timer tick, asking Buildbucket for the build status
[13:31:41.395] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:41.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:121:0) after 1m0s
[13:32:41.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:121:0)
[13:32:41.505] Timer tick, asking Buildbucket for the build status
[13:32:41.646] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:41.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:122:0) after 1m0s
[13:33:41.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:122:0)
[13:33:41.653] Timer tick, asking Buildbucket for the build status
[13:33:42.019] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:42.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:123:0) after 1m0s
[13:34:42.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:123:0)
[13:34:42.141] Timer tick, asking Buildbucket for the build status
[13:34:42.335] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:42.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:124:0) after 1m0s
[13:35:42.379] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:124:0)
[13:35:42.379] Timer tick, asking Buildbucket for the build status
[13:35:42.521] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:42.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:125:0) after 1m0s
[13:36:42.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:125:0)
[13:36:42.602] Timer tick, asking Buildbucket for the build status
[13:36:44.307] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:44.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:126:0) after 1m0s
[13:37:44.426] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:126:0)
[13:37:44.426] Timer tick, asking Buildbucket for the build status
[13:37:44.578] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:44.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:127:0) after 1m0s
[13:38:44.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:127:0)
[13:38:44.715] Timer tick, asking Buildbucket for the build status
[13:38:44.944] Build 8871721951896139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:44.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037152014750231040:128:0) after 1m0s
[13:39:44.912] Received PubSub notification, asking Buildbucket for the build status
[13:39:44.999] Build 8871721951896139760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:39:44.999] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597671584239530",
"created_by": "project:chromium",
"created_ts": "1597663654387433",
"id": "8871721951896139760",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d3f2565851bccc3434367bebf60110f9c65338e5\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-8-yk3i\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 365, \"got_angle_revision\": \"d852ad24adc0c62ecb520835c1a835a42d3495e2\", \"got_dawn_revision\": \"5e9b29fab92d6d79125555ea0d84afde9ace908b\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"d3f2565851bccc3434367bebf60110f9c65338e5\", \"got_revision_cp\": \"refs/heads/master@{#798591}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"abe07b9438553f741e776dedb59a2d4b502a9983\", \"got_v8_revision\": \"f8df7e0af9c692d5e3b3aa3aa4bd17aa686d4134\", \"got_v8_revision_cp\": \"refs/heads/8.6.387@{#1}\", \"got_webrtc_revision\": \"383f2cfca4a1ebd9626134101fe70d5a716b2d92\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31921}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d3f2565851bccc3434367bebf60110f9c65338e5\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-yk3i\"], \"image\": [\"chrome-win10-1703-20081200-b8de48ff3cf\"], \"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\": [\"5488-fc78d20\"], \"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": "1597663659510024",
"status": "COMPLETED",
"status_changed_ts": "1597671584636297",
"tags": [
"build_address:luci.chromium.ci/win-official/365",
"builder:win-official",
"buildset:commit/git/d3f2565851bccc3434367bebf60110f9c65338e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d3f2565851bccc3434367bebf60110f9c65338e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037152014750231040",
"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/8871721951896139760/+/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:4e14fe4041f39c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1597671584640670",
"url": "https://ci.chromium.org/b/8871721951896139760",
"utcnow_ts": "1597671584980045"
}
}
[13:39:44.999] Invocation finished in 2h12m12.717954793s with status SUCCEEDED