[05:25:37.246] New invocation is queued and will start shortly
[05:25:38.319] Starting the invocation (attempt 1)
[05:25:38.446] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:25:38.446] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:25:38.446] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030108221254285424",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d11697651066b0e6f58ddb193471996fb0063144\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9030108221254285424",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/d11697651066b0e6f58ddb193471996fb0063144",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d11697651066b0e6f58ddb193471996fb0063144",
"gitiles_ref:refs/heads/master"
]
}
[05:25:39.189] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604381138606462",
"id": "8864678159163724768",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d11697651066b0e6f58ddb193471996fb0063144\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604381139061251",
"tags": [
"build_address:luci.chromium.ci/win32-official/867",
"builder:win32-official",
"buildset:commit/git/d11697651066b0e6f58ddb193471996fb0063144",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d11697651066b0e6f58ddb193471996fb0063144",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030108221254285424",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8864678159163724768/+/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": "1604381139061369",
"url": "https://ci.chromium.org/b/8864678159163724768",
"utcnow_ts": "1604381139183249"
}
}
[05:25:39.190] Task URL: https://ci.chromium.org/b/8864678159163724768
[05:25:39.190] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:2:0) after 1m0s
[05:26:39.329] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:2:0)
[05:26:39.329] Timer tick, asking Buildbucket for the build status
[05:26:39.659] Build 8864678159163724768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:26:39.659] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:3:0) after 1m0s
[05:27:39.770] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:3:0)
[05:27:39.770] Timer tick, asking Buildbucket for the build status
[05:27:39.861] Build 8864678159163724768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:27:39.861] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:4:0) after 1m0s
[05:28:40.029] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:4:0)
[05:28:40.029] Timer tick, asking Buildbucket for the build status
[05:28:40.099] Build 8864678159163724768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:28:40.099] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:5:0) after 1m0s
[05:29:40.137] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:5:0)
[05:29:40.137] Timer tick, asking Buildbucket for the build status
[05:29:40.206] Build 8864678159163724768: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:29:40.206] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:6:0) after 1m0s
[05:30:25.369] Received PubSub notification, asking Buildbucket for the build status
[05:30:25.470] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:40.605] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:6:0)
[05:30:40.605] Timer tick, asking Buildbucket for the build status
[05:30:40.679] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:40.679] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:8:0) after 1m0s
[05:31:40.887] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:8:0)
[05:31:40.887] Timer tick, asking Buildbucket for the build status
[05:31:40.998] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:40.998] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:9:0) after 1m0s
[05:32:41.115] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:9:0)
[05:32:41.115] Timer tick, asking Buildbucket for the build status
[05:32:41.253] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:41.253] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:10:0) after 1m0s
[05:33:41.311] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:10:0)
[05:33:41.311] Timer tick, asking Buildbucket for the build status
[05:33:41.419] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:41.419] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:11:0) after 1m0s
[05:34:41.532] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:11:0)
[05:34:41.532] Timer tick, asking Buildbucket for the build status
[05:34:41.628] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:41.628] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:12:0) after 1m0s
[05:35:41.776] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:12:0)
[05:35:41.776] Timer tick, asking Buildbucket for the build status
[05:35:41.880] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:41.880] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:13:0) after 1m0s
[05:36:42.109] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:13:0)
[05:36:42.109] Timer tick, asking Buildbucket for the build status
[05:36:42.214] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:42.214] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:14:0) after 1m0s
[05:37:42.296] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:14:0)
[05:37:42.296] Timer tick, asking Buildbucket for the build status
[05:37:42.418] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:42.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:15:0) after 1m0s
[05:38:42.604] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:15:0)
[05:38:42.604] Timer tick, asking Buildbucket for the build status
[05:38:42.710] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:42.710] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:16:0) after 1m0s
[05:39:42.731] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:16:0)
[05:39:42.731] Timer tick, asking Buildbucket for the build status
[05:39:42.942] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:42.942] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:17:0) after 1m0s
[05:40:42.935] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:17:0)
[05:40:42.935] Timer tick, asking Buildbucket for the build status
[05:40:43.654] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:43.654] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:18:0) after 1m0s
[05:41:43.737] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:18:0)
[05:41:43.737] Timer tick, asking Buildbucket for the build status
[05:41:43.924] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:43.925] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:19:0) after 1m0s
[05:42:43.959] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:19:0)
[05:42:43.959] Timer tick, asking Buildbucket for the build status
[05:42:44.020] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:44.020] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:20:0) after 1m0s
[05:43:44.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:20:0)
[05:43:44.076] Timer tick, asking Buildbucket for the build status
[05:43:44.136] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:44.136] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:21:0) after 1m0s
[05:44:44.204] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:21:0)
[05:44:44.204] Timer tick, asking Buildbucket for the build status
[05:44:44.377] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:44.377] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:22:0) after 1m0s
[05:45:44.415] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:22:0)
[05:45:44.415] Timer tick, asking Buildbucket for the build status
[05:45:44.520] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:44.520] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:23:0) after 1m0s
[05:46:44.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:23:0)
[05:46:44.561] Timer tick, asking Buildbucket for the build status
[05:46:44.731] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:44.731] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:24:0) after 1m0s
[05:47:44.756] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:24:0)
[05:47:44.756] Timer tick, asking Buildbucket for the build status
[05:47:44.819] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:44.819] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:25:0) after 1m0s
[05:48:44.855] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:25:0)
[05:48:44.855] Timer tick, asking Buildbucket for the build status
[05:48:44.938] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:44.938] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:26:0) after 1m0s
[05:49:44.970] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:26:0)
[05:49:44.970] Timer tick, asking Buildbucket for the build status
[05:49:45.070] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:45.070] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:27:0) after 1m0s
[05:50:45.145] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:27:0)
[05:50:45.145] Timer tick, asking Buildbucket for the build status
[05:50:45.219] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:45.219] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:28:0) after 1m0s
[05:51:45.279] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:28:0)
[05:51:45.279] Timer tick, asking Buildbucket for the build status
[05:51:45.714] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:45.714] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:29:0) after 1m0s
[05:52:45.804] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:29:0)
[05:52:45.804] Timer tick, asking Buildbucket for the build status
[05:52:45.863] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:45.863] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:30:0) after 1m0s
[05:53:46.000] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:30:0)
[05:53:46.000] Timer tick, asking Buildbucket for the build status
[05:53:46.072] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:46.072] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:31:0) after 1m0s
[05:54:46.145] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:31:0)
[05:54:46.145] Timer tick, asking Buildbucket for the build status
[05:54:46.216] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:46.216] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:32:0) after 1m0s
[05:55:46.267] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:32:0)
[05:55:46.267] Timer tick, asking Buildbucket for the build status
[05:55:46.369] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:46.369] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:33:0) after 1m0s
[05:56:46.389] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:33:0)
[05:56:46.389] Timer tick, asking Buildbucket for the build status
[05:56:46.489] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:46.489] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:34:0) after 1m0s
[05:57:48.476] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:34:0)
[05:57:48.476] Timer tick, asking Buildbucket for the build status
[05:57:48.853] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:48.853] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:35:0) after 1m0s
[05:58:48.854] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:35:0)
[05:58:48.854] Timer tick, asking Buildbucket for the build status
[05:58:48.954] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:48.954] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:36:0) after 1m0s
[05:59:49.343] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:36:0)
[05:59:49.343] Timer tick, asking Buildbucket for the build status
[05:59:50.076] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:50.076] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:37:0) after 1m0s
[06:00:50.160] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:37:0)
[06:00:50.160] Timer tick, asking Buildbucket for the build status
[06:00:50.310] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:50.310] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:38:0) after 1m0s
[06:01:50.513] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:38:0)
[06:01:50.513] Timer tick, asking Buildbucket for the build status
[06:01:50.650] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:50.650] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:39:0) after 1m0s
[06:02:50.762] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:39:0)
[06:02:50.762] Timer tick, asking Buildbucket for the build status
[06:02:50.871] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:50.871] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:40:0) after 1m0s
[06:03:50.998] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:40:0)
[06:03:50.998] Timer tick, asking Buildbucket for the build status
[06:03:51.144] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:51.144] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:41:0) after 1m0s
[06:04:51.207] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:41:0)
[06:04:51.207] Timer tick, asking Buildbucket for the build status
[06:04:51.283] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:51.283] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:42:0) after 1m0s
[06:05:51.343] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:42:0)
[06:05:51.343] Timer tick, asking Buildbucket for the build status
[06:05:51.585] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:51.585] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:43:0) after 1m0s
[06:06:51.645] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:43:0)
[06:06:51.645] Timer tick, asking Buildbucket for the build status
[06:06:51.714] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:51.714] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:44:0) after 1m0s
[06:07:51.826] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:44:0)
[06:07:51.826] Timer tick, asking Buildbucket for the build status
[06:07:51.884] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:51.884] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:45:0) after 1m0s
[06:08:51.937] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:45:0)
[06:08:51.937] Timer tick, asking Buildbucket for the build status
[06:08:52.031] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:52.031] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:46:0) after 1m0s
[06:09:52.140] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:46:0)
[06:09:52.140] Timer tick, asking Buildbucket for the build status
[06:09:52.234] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:52.234] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:47:0) after 1m0s
[06:10:52.367] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:47:0)
[06:10:52.367] Timer tick, asking Buildbucket for the build status
[06:10:52.517] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:52.517] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:48:0) after 1m0s
[06:11:52.524] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:48:0)
[06:11:52.524] Timer tick, asking Buildbucket for the build status
[06:11:52.587] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:52.587] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:49:0) after 1m0s
[06:12:52.649] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:49:0)
[06:12:52.649] Timer tick, asking Buildbucket for the build status
[06:12:52.743] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:52.743] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:50:0) after 1m0s
[06:13:52.746] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:50:0)
[06:13:52.746] Timer tick, asking Buildbucket for the build status
[06:13:52.813] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:52.813] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:51:0) after 1m0s
[06:14:52.841] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:51:0)
[06:14:52.841] Timer tick, asking Buildbucket for the build status
[06:14:52.921] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:52.921] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:52:0) after 1m0s
[06:15:52.946] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:52:0)
[06:15:52.946] Timer tick, asking Buildbucket for the build status
[06:15:53.357] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:53.357] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:53:0) after 1m0s
[06:16:53.362] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:53:0)
[06:16:53.362] Timer tick, asking Buildbucket for the build status
[06:16:53.623] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:53.623] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:54:0) after 1m0s
[06:17:53.648] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:54:0)
[06:17:53.648] Timer tick, asking Buildbucket for the build status
[06:17:53.788] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:53.789] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:55:0) after 1m0s
[06:18:53.862] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:55:0)
[06:18:53.862] Timer tick, asking Buildbucket for the build status
[06:18:53.963] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:53.963] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:56:0) after 1m0s
[06:19:54.004] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:56:0)
[06:19:54.004] Timer tick, asking Buildbucket for the build status
[06:19:54.102] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:54.102] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:57:0) after 1m0s
[06:20:54.129] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:57:0)
[06:20:54.129] Timer tick, asking Buildbucket for the build status
[06:20:54.232] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:54.232] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:58:0) after 1m0s
[06:21:54.287] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:58:0)
[06:21:54.287] Timer tick, asking Buildbucket for the build status
[06:21:54.388] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:54.388] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:59:0) after 1m0s
[06:22:54.412] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:59:0)
[06:22:54.412] Timer tick, asking Buildbucket for the build status
[06:22:54.487] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:54.487] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:60:0) after 1m0s
[06:23:54.553] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:60:0)
[06:23:54.553] Timer tick, asking Buildbucket for the build status
[06:23:54.643] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:54.643] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:61:0) after 1m0s
[06:24:54.734] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:61:0)
[06:24:54.734] Timer tick, asking Buildbucket for the build status
[06:24:54.854] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:54.854] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:62:0) after 1m0s
[06:25:54.876] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:62:0)
[06:25:54.876] Timer tick, asking Buildbucket for the build status
[06:25:54.978] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:54.978] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:63:0) after 1m0s
[06:26:55.064] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:63:0)
[06:26:55.064] Timer tick, asking Buildbucket for the build status
[06:26:55.129] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:55.129] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:64:0) after 1m0s
[06:27:55.209] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:64:0)
[06:27:55.209] Timer tick, asking Buildbucket for the build status
[06:27:55.483] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:55.483] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:65:0) after 1m0s
[06:28:55.546] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:65:0)
[06:28:55.546] Timer tick, asking Buildbucket for the build status
[06:28:55.609] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:55.609] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:66:0) after 1m0s
[06:29:55.625] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:66:0)
[06:29:55.625] Timer tick, asking Buildbucket for the build status
[06:29:55.713] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:55.713] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:67:0) after 1m0s
[06:30:55.741] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:67:0)
[06:30:55.741] Timer tick, asking Buildbucket for the build status
[06:30:55.842] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:55.843] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:68:0) after 1m0s
[06:31:55.853] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:68:0)
[06:31:55.853] Timer tick, asking Buildbucket for the build status
[06:31:55.928] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:55.928] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:69:0) after 1m0s
[06:32:55.954] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:69:0)
[06:32:55.954] Timer tick, asking Buildbucket for the build status
[06:32:56.012] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:56.012] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:70:0) after 1m0s
[06:33:56.027] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:70:0)
[06:33:56.027] Timer tick, asking Buildbucket for the build status
[06:33:56.096] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:56.096] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:71:0) after 1m0s
[06:34:56.226] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:71:0)
[06:34:56.226] Timer tick, asking Buildbucket for the build status
[06:34:56.447] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:56.447] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:72:0) after 1m0s
[06:35:56.476] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:72:0)
[06:35:56.476] Timer tick, asking Buildbucket for the build status
[06:35:56.680] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:56.680] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:73:0) after 1m0s
[06:36:56.746] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:73:0)
[06:36:56.746] Timer tick, asking Buildbucket for the build status
[06:36:56.828] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:56.828] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:74:0) after 1m0s
[06:37:56.933] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:74:0)
[06:37:56.933] Timer tick, asking Buildbucket for the build status
[06:37:57.002] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:57.002] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:75:0) after 1m0s
[06:38:57.026] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:75:0)
[06:38:57.026] Timer tick, asking Buildbucket for the build status
[06:38:57.155] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:57.155] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:76:0) after 1m0s
[06:39:57.196] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:76:0)
[06:39:57.196] Timer tick, asking Buildbucket for the build status
[06:39:57.286] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:57.286] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:77:0) after 1m0s
[06:40:57.317] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:77:0)
[06:40:57.317] Timer tick, asking Buildbucket for the build status
[06:40:58.108] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:58.108] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:78:0) after 1m0s
[06:41:58.173] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:78:0)
[06:41:58.173] Timer tick, asking Buildbucket for the build status
[06:41:58.254] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:58.254] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:79:0) after 1m0s
[06:42:58.436] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:79:0)
[06:42:58.436] Timer tick, asking Buildbucket for the build status
[06:42:58.521] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:58.521] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:80:0) after 1m0s
[06:43:58.587] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:80:0)
[06:43:58.587] Timer tick, asking Buildbucket for the build status
[06:43:58.645] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:58.645] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:81:0) after 1m0s
[06:44:58.670] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:81:0)
[06:44:58.670] Timer tick, asking Buildbucket for the build status
[06:44:58.733] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:58.733] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:82:0) after 1m0s
[06:45:58.776] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:82:0)
[06:45:58.776] Timer tick, asking Buildbucket for the build status
[06:45:58.912] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:58.912] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:83:0) after 1m0s
[06:46:59.004] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:83:0)
[06:46:59.004] Timer tick, asking Buildbucket for the build status
[06:46:59.341] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:59.341] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:84:0) after 1m0s
[06:47:59.399] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:84:0)
[06:47:59.399] Timer tick, asking Buildbucket for the build status
[06:48:00.306] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:00.306] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:85:0) after 1m0s
[06:49:00.384] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:85:0)
[06:49:00.384] Timer tick, asking Buildbucket for the build status
[06:49:00.588] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:00.588] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:86:0) after 1m0s
[06:50:00.632] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:86:0)
[06:50:00.632] Timer tick, asking Buildbucket for the build status
[06:50:00.725] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:00.725] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:87:0) after 1m0s
[06:51:01.205] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:87:0)
[06:51:01.205] Timer tick, asking Buildbucket for the build status
[06:51:01.338] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:01.338] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:88:0) after 1m0s
[06:52:01.722] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:88:0)
[06:52:01.722] Timer tick, asking Buildbucket for the build status
[06:52:01.928] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:01.928] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:89:0) after 1m0s
[06:53:02.077] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:89:0)
[06:53:02.077] Timer tick, asking Buildbucket for the build status
[06:53:02.381] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:02.381] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:90:0) after 1m0s
[06:54:02.411] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:90:0)
[06:54:02.412] Timer tick, asking Buildbucket for the build status
[06:54:02.518] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:02.518] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:91:0) after 1m0s
[06:55:02.575] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:91:0)
[06:55:02.575] Timer tick, asking Buildbucket for the build status
[06:55:02.641] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:02.641] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:92:0) after 1m0s
[06:56:02.666] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:92:0)
[06:56:02.666] Timer tick, asking Buildbucket for the build status
[06:56:02.818] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:02.818] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:93:0) after 1m0s
[06:57:03.067] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:93:0)
[06:57:03.067] Timer tick, asking Buildbucket for the build status
[06:57:03.145] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:03.145] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:94:0) after 1m0s
[06:58:03.201] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:94:0)
[06:58:03.201] Timer tick, asking Buildbucket for the build status
[06:58:03.278] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:03.278] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:95:0) after 1m0s
[06:59:03.376] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:95:0)
[06:59:03.376] Timer tick, asking Buildbucket for the build status
[06:59:03.471] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:03.471] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:96:0) after 1m0s
[07:00:03.463] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:96:0)
[07:00:03.463] Timer tick, asking Buildbucket for the build status
[07:00:03.687] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:03.687] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:97:0) after 1m0s
[07:01:03.717] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:97:0)
[07:01:03.717] Timer tick, asking Buildbucket for the build status
[07:01:03.920] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:03.920] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:98:0) after 1m0s
[07:02:03.945] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:98:0)
[07:02:03.945] Timer tick, asking Buildbucket for the build status
[07:02:04.038] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:04.038] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:99:0) after 1m0s
[07:03:04.247] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:99:0)
[07:03:04.247] Timer tick, asking Buildbucket for the build status
[07:03:04.493] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:04.493] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:100:0) after 1m0s
[07:04:04.514] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:100:0)
[07:04:04.514] Timer tick, asking Buildbucket for the build status
[07:04:04.612] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:04.612] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:101:0) after 1m0s
[07:05:04.646] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:101:0)
[07:05:04.646] Timer tick, asking Buildbucket for the build status
[07:05:04.754] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:04.754] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:102:0) after 1m0s
[07:06:04.808] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:102:0)
[07:06:04.808] Timer tick, asking Buildbucket for the build status
[07:06:04.938] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:04.938] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:103:0) after 1m0s
[07:07:04.976] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:103:0)
[07:07:04.976] Timer tick, asking Buildbucket for the build status
[07:07:05.138] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:05.138] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:104:0) after 1m0s
[07:08:05.220] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:104:0)
[07:08:05.220] Timer tick, asking Buildbucket for the build status
[07:08:05.310] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:05.310] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:105:0) after 1m0s
[07:09:05.431] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:105:0)
[07:09:05.431] Timer tick, asking Buildbucket for the build status
[07:09:05.666] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:05.666] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:106:0) after 1m0s
[07:10:05.653] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:106:0)
[07:10:05.653] Timer tick, asking Buildbucket for the build status
[07:10:05.840] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:05.840] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:107:0) after 1m0s
[07:11:05.855] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:107:0)
[07:11:05.855] Timer tick, asking Buildbucket for the build status
[07:11:05.970] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:05.970] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:108:0) after 1m0s
[07:12:06.059] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:108:0)
[07:12:06.059] Timer tick, asking Buildbucket for the build status
[07:12:06.137] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:06.137] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:109:0) after 1m0s
[07:13:06.185] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:109:0)
[07:13:06.185] Timer tick, asking Buildbucket for the build status
[07:13:06.246] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:06.246] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:110:0) after 1m0s
[07:14:06.320] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:110:0)
[07:14:06.320] Timer tick, asking Buildbucket for the build status
[07:14:06.406] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:06.406] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:111:0) after 1m0s
[07:15:06.476] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:111:0)
[07:15:06.476] Timer tick, asking Buildbucket for the build status
[07:15:08.518] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:08.518] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:112:0) after 1m0s
[07:16:08.562] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:112:0)
[07:16:08.562] Timer tick, asking Buildbucket for the build status
[07:16:08.739] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:08.739] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:113:0) after 1m0s
[07:17:08.839] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:113:0)
[07:17:08.839] Timer tick, asking Buildbucket for the build status
[07:17:08.914] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:08.914] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:114:0) after 1m0s
[07:18:09.006] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:114:0)
[07:18:09.006] Timer tick, asking Buildbucket for the build status
[07:18:09.266] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:09.266] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:115:0) after 1m0s
[07:19:09.297] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:115:0)
[07:19:09.297] Timer tick, asking Buildbucket for the build status
[07:19:09.407] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:09.407] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:116:0) after 1m0s
[07:20:09.436] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:116:0)
[07:20:09.436] Timer tick, asking Buildbucket for the build status
[07:20:09.540] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:09.540] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:117:0) after 1m0s
[07:21:09.564] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:117:0)
[07:21:09.564] Timer tick, asking Buildbucket for the build status
[07:21:09.665] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:09.665] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:118:0) after 1m0s
[07:22:09.680] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:118:0)
[07:22:09.680] Timer tick, asking Buildbucket for the build status
[07:22:09.771] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:09.771] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:119:0) after 1m0s
[07:23:09.902] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:119:0)
[07:23:09.902] Timer tick, asking Buildbucket for the build status
[07:23:10.087] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:10.087] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:120:0) after 1m0s
[07:24:10.109] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:120:0)
[07:24:10.109] Timer tick, asking Buildbucket for the build status
[07:24:10.199] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:10.199] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:121:0) after 1m0s
[07:25:10.294] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:121:0)
[07:25:10.294] Timer tick, asking Buildbucket for the build status
[07:25:10.372] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:10.373] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:122:0) after 1m0s
[07:26:10.405] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:122:0)
[07:26:10.405] Timer tick, asking Buildbucket for the build status
[07:26:10.690] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:10.690] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:123:0) after 1m0s
[07:27:10.684] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:123:0)
[07:27:10.684] Timer tick, asking Buildbucket for the build status
[07:27:10.807] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:10.807] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:124:0) after 1m0s
[07:28:10.848] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:124:0)
[07:28:10.848] Timer tick, asking Buildbucket for the build status
[07:28:10.921] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:10.921] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:125:0) after 1m0s
[07:29:10.986] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:125:0)
[07:29:10.986] Timer tick, asking Buildbucket for the build status
[07:29:11.040] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:11.040] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:126:0) after 1m0s
[07:30:11.096] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:126:0)
[07:30:11.096] Timer tick, asking Buildbucket for the build status
[07:30:11.261] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:11.261] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:127:0) after 1m0s
[07:31:11.326] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:127:0)
[07:31:11.326] Timer tick, asking Buildbucket for the build status
[07:31:11.452] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:11.452] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:128:0) after 1m0s
[07:32:11.512] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:128:0)
[07:32:11.512] Timer tick, asking Buildbucket for the build status
[07:32:11.572] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:11.572] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:129:0) after 1m0s
[07:33:11.581] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:129:0)
[07:33:11.581] Timer tick, asking Buildbucket for the build status
[07:33:11.648] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:11.648] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:130:0) after 1m0s
[07:34:11.704] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:130:0)
[07:34:11.704] Timer tick, asking Buildbucket for the build status
[07:34:11.774] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:11.774] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:131:0) after 1m0s
[07:35:11.802] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:131:0)
[07:35:11.802] Timer tick, asking Buildbucket for the build status
[07:35:11.858] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:11.858] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:132:0) after 1m0s
[07:36:11.897] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:132:0)
[07:36:11.897] Timer tick, asking Buildbucket for the build status
[07:36:12.154] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:12.154] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:133:0) after 1m0s
[07:37:12.169] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:133:0)
[07:37:12.169] Timer tick, asking Buildbucket for the build status
[07:37:12.248] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:12.248] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:134:0) after 1m0s
[07:38:12.342] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:134:0)
[07:38:12.342] Timer tick, asking Buildbucket for the build status
[07:38:12.418] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:12.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:135:0) after 1m0s
[07:39:12.435] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:135:0)
[07:39:12.435] Timer tick, asking Buildbucket for the build status
[07:39:12.745] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:12.745] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:136:0) after 1m0s
[07:40:12.836] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:136:0)
[07:40:12.836] Timer tick, asking Buildbucket for the build status
[07:40:13.003] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:13.003] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:137:0) after 1m0s
[07:41:13.053] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:137:0)
[07:41:13.053] Timer tick, asking Buildbucket for the build status
[07:41:13.171] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:13.171] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:138:0) after 1m0s
[07:42:13.403] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:138:0)
[07:42:13.403] Timer tick, asking Buildbucket for the build status
[07:42:13.468] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:13.468] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:139:0) after 1m0s
[07:43:13.807] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:139:0)
[07:43:13.807] Timer tick, asking Buildbucket for the build status
[07:43:13.931] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:13.931] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:140:0) after 1m0s
[07:44:14.062] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:140:0)
[07:44:14.062] Timer tick, asking Buildbucket for the build status
[07:44:14.136] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:14.136] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:141:0) after 1m0s
[07:45:14.328] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:141:0)
[07:45:14.328] Timer tick, asking Buildbucket for the build status
[07:45:14.409] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:14.409] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:142:0) after 1m0s
[07:46:14.513] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:142:0)
[07:46:14.513] Timer tick, asking Buildbucket for the build status
[07:46:14.617] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:14.617] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:143:0) after 1m0s
[07:47:14.751] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:143:0)
[07:47:14.751] Timer tick, asking Buildbucket for the build status
[07:47:14.833] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:14.833] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:144:0) after 1m0s
[07:48:14.928] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:144:0)
[07:48:14.928] Timer tick, asking Buildbucket for the build status
[07:48:15.016] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:15.016] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:145:0) after 1m0s
[07:49:15.197] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:145:0)
[07:49:15.197] Timer tick, asking Buildbucket for the build status
[07:49:15.274] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:15.274] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:146:0) after 1m0s
[07:50:19.846] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:146:0)
[07:50:19.846] Timer tick, asking Buildbucket for the build status
[07:50:19.925] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:19.925] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:147:0) after 1m0s
[07:51:19.947] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:147:0)
[07:51:19.947] Timer tick, asking Buildbucket for the build status
[07:51:20.623] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:20.623] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:148:0) after 1m0s
[07:52:20.702] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:148:0)
[07:52:20.702] Timer tick, asking Buildbucket for the build status
[07:52:20.767] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:20.767] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:149:0) after 1m0s
[07:53:20.842] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:149:0)
[07:53:20.843] Timer tick, asking Buildbucket for the build status
[07:53:20.960] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:20.960] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:150:0) after 1m0s
[07:54:21.000] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:150:0)
[07:54:21.000] Timer tick, asking Buildbucket for the build status
[07:54:21.067] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:21.067] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:151:0) after 1m0s
[07:55:21.164] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:151:0)
[07:55:21.164] Timer tick, asking Buildbucket for the build status
[07:55:21.387] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:21.387] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:152:0) after 1m0s
[07:56:21.416] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:152:0)
[07:56:21.416] Timer tick, asking Buildbucket for the build status
[07:56:21.554] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:21.554] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:153:0) after 1m0s
[07:57:21.614] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:153:0)
[07:57:21.614] Timer tick, asking Buildbucket for the build status
[07:57:21.681] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:21.681] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:154:0) after 1m0s
[07:58:21.713] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:154:0)
[07:58:21.713] Timer tick, asking Buildbucket for the build status
[07:58:21.771] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:21.771] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:155:0) after 1m0s
[07:59:21.810] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:155:0)
[07:59:21.810] Timer tick, asking Buildbucket for the build status
[07:59:21.878] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:21.878] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:156:0) after 1m0s
[08:00:21.993] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:156:0)
[08:00:21.993] Timer tick, asking Buildbucket for the build status
[08:00:22.166] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:22.166] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:157:0) after 1m0s
[08:01:22.211] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:157:0)
[08:01:22.211] Timer tick, asking Buildbucket for the build status
[08:01:22.326] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:22.326] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:158:0) after 1m0s
[08:02:22.359] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:158:0)
[08:02:22.359] Timer tick, asking Buildbucket for the build status
[08:02:22.505] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:22.505] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:159:0) after 1m0s
[08:03:22.532] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:159:0)
[08:03:22.532] Timer tick, asking Buildbucket for the build status
[08:03:22.599] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:22.599] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:160:0) after 1m0s
[08:04:22.645] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:160:0)
[08:04:22.645] Timer tick, asking Buildbucket for the build status
[08:04:22.716] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:22.716] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:161:0) after 1m0s
[08:05:22.742] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:161:0)
[08:05:22.742] Timer tick, asking Buildbucket for the build status
[08:05:22.916] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:22.916] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:162:0) after 1m0s
[08:06:22.955] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:162:0)
[08:06:22.955] Timer tick, asking Buildbucket for the build status
[08:06:23.061] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:23.061] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:163:0) after 1m0s
[08:07:23.150] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:163:0)
[08:07:23.150] Timer tick, asking Buildbucket for the build status
[08:07:23.225] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:23.225] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:164:0) after 1m0s
[08:08:23.233] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:164:0)
[08:08:23.233] Timer tick, asking Buildbucket for the build status
[08:08:23.401] Build 8864678159163724768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:23.401] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030108221254285424:165:0) after 1m0s
[08:08:29.353] Received PubSub notification, asking Buildbucket for the build status
[08:08:29.416] Build 8864678159163724768: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:08:29.416] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604390908208580",
"created_by": "project:chromium",
"created_ts": "1604381138606462",
"id": "8864678159163724768",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d11697651066b0e6f58ddb193471996fb0063144\"}}",
"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\": \"d11697651066b0e6f58ddb193471996fb0063144\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-7-tlym\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 867, \"got_angle_revision\": \"ed916ddf61cc0021cebf55fb6901ba71fe9e6a2e\", \"got_dawn_revision\": \"71d2e1d23d49c3616209dbf4776fd720aaa11560\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"d11697651066b0e6f58ddb193471996fb0063144\", \"got_revision_cp\": \"refs/heads/master@{#823449}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"959f41988a18e8511dcde1d646d798de4ea6b59d\", \"got_v8_revision\": \"037ccdfb11c6a626d405939fc582f85d0a8bebb6\", \"got_v8_revision_cp\": \"refs/heads/8.8.218@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d11697651066b0e6f58ddb193471996fb0063144\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f2b006d1778b1676853cdf5ef47b6b4ca966a5d1d64c9da204abd3981f53b685_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-7-tlym\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5614-d862eaa\"], \"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": "1604381423271917",
"status": "COMPLETED",
"status_changed_ts": "1604390908839699",
"tags": [
"build_address:luci.chromium.ci/win32-official/867",
"builder:win32-official",
"buildset:commit/git/d11697651066b0e6f58ddb193471996fb0063144",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d11697651066b0e6f58ddb193471996fb0063144",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030108221254285424",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8864678159163724768/+/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:4fa562fa3ed86d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604390908850356",
"url": "https://ci.chromium.org/b/8864678159163724768",
"utcnow_ts": "1604390909408523"
}
}
[08:08:29.416] Invocation finished in 2h42m52.187871271s with status SUCCEEDED