[09:49:00.747] New invocation is queued and will start shortly
[09:49:05.608] Starting the invocation (attempt 1)
[09:49:05.691] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:49:05.691] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:49:05.692] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037611198308408384",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"77a5bff862df162ec25b1b5164deada040e0f3ce\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9037611198308408384",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/77a5bff862df162ec25b1b5164deada040e0f3ce",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77a5bff862df162ec25b1b5164deada040e0f3ce",
"gitiles_ref:refs/heads/master"
]
}
[09:49:06.453] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597225745858734",
"id": "8872181132269158192",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"77a5bff862df162ec25b1b5164deada040e0f3ce\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597225746339806",
"tags": [
"build_address:luci.chromium.ci/win-official/322",
"builder:win-official",
"buildset:commit/git/77a5bff862df162ec25b1b5164deada040e0f3ce",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77a5bff862df162ec25b1b5164deada040e0f3ce",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037611198308408384",
"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/8872181132269158192/+/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": "1597225746339932",
"url": "https://ci.chromium.org/b/8872181132269158192",
"utcnow_ts": "1597225746444934"
}
}
[09:49:06.453] Task URL: https://ci.chromium.org/b/8872181132269158192
[09:49:06.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:2:0) after 1m0s
[09:50:06.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:2:0)
[09:50:06.438] Timer tick, asking Buildbucket for the build status
[09:50:06.615] Build 8872181132269158192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:06.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:3:0) after 1m0s
[09:51:06.654] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:3:0)
[09:51:06.654] Timer tick, asking Buildbucket for the build status
[09:51:06.923] Build 8872181132269158192: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:06.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:4:0) after 1m0s
[09:51:19.823] Received PubSub notification, asking Buildbucket for the build status
[09:51:19.901] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:07.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:4:0)
[09:52:07.029] Timer tick, asking Buildbucket for the build status
[09:52:07.411] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:07.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:6:0) after 1m0s
[09:53:07.436] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:6:0)
[09:53:07.436] Timer tick, asking Buildbucket for the build status
[09:53:08.861] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:08.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:7:0) after 1m0s
[09:54:09.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:7:0)
[09:54:09.058] Timer tick, asking Buildbucket for the build status
[09:54:09.325] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:09.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:8:0) after 1m0s
[09:55:09.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:8:0)
[09:55:09.407] Timer tick, asking Buildbucket for the build status
[09:55:09.633] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:09.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:9:0) after 1m0s
[09:56:09.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:9:0)
[09:56:09.727] Timer tick, asking Buildbucket for the build status
[09:56:09.847] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:09.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:10:0) after 1m0s
[09:57:09.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:10:0)
[09:57:09.903] Timer tick, asking Buildbucket for the build status
[09:57:09.982] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:09.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:11:0) after 1m0s
[09:58:10.049] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:11:0)
[09:58:10.049] Timer tick, asking Buildbucket for the build status
[09:58:10.199] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:10.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:12:0) after 1m0s
[09:59:10.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:12:0)
[09:59:10.258] Timer tick, asking Buildbucket for the build status
[09:59:11.438] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:11.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:13:0) after 1m0s
[10:00:11.450] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:13:0)
[10:00:11.450] Timer tick, asking Buildbucket for the build status
[10:00:11.844] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:11.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:14:0) after 1m0s
[10:01:11.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:14:0)
[10:01:11.927] Timer tick, asking Buildbucket for the build status
[10:01:12.526] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:12.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:15:0) after 1m0s
[10:02:12.654] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:15:0)
[10:02:12.654] Timer tick, asking Buildbucket for the build status
[10:02:13.111] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:13.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:16:0) after 1m0s
[10:03:13.190] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:16:0)
[10:03:13.190] Timer tick, asking Buildbucket for the build status
[10:03:13.282] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:13.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:17:0) after 1m0s
[10:04:13.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:17:0)
[10:04:13.404] Timer tick, asking Buildbucket for the build status
[10:04:13.588] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:13.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:18:0) after 1m0s
[10:05:13.690] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:18:0)
[10:05:13.690] Timer tick, asking Buildbucket for the build status
[10:05:13.760] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:13.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:19:0) after 1m0s
[10:06:13.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:19:0)
[10:06:13.800] Timer tick, asking Buildbucket for the build status
[10:06:13.892] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:13.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:20:0) after 1m0s
[10:07:14.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:20:0)
[10:07:14.165] Timer tick, asking Buildbucket for the build status
[10:07:14.234] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:14.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:21:0) after 1m0s
[10:08:14.380] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:21:0)
[10:08:14.380] Timer tick, asking Buildbucket for the build status
[10:08:14.544] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:14.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:22:0) after 1m0s
[10:09:14.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:22:0)
[10:09:14.551] Timer tick, asking Buildbucket for the build status
[10:09:14.766] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:14.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:23:0) after 1m0s
[10:10:14.826] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:23:0)
[10:10:14.826] Timer tick, asking Buildbucket for the build status
[10:10:15.027] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:15.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:24:0) after 1m0s
[10:11:15.134] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:24:0)
[10:11:15.134] Timer tick, asking Buildbucket for the build status
[10:11:15.282] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:15.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:25:0) after 1m0s
[10:12:15.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:25:0)
[10:12:15.274] Timer tick, asking Buildbucket for the build status
[10:12:15.356] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:15.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:26:0) after 1m0s
[10:13:15.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:26:0)
[10:13:15.378] Timer tick, asking Buildbucket for the build status
[10:13:15.458] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:15.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:27:0) after 1m0s
[10:14:15.523] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:27:0)
[10:14:15.523] Timer tick, asking Buildbucket for the build status
[10:14:15.675] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:15.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:28:0) after 1m0s
[10:15:15.778] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:28:0)
[10:15:15.778] Timer tick, asking Buildbucket for the build status
[10:15:15.882] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:15.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:29:0) after 1m0s
[10:16:15.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:29:0)
[10:16:15.941] Timer tick, asking Buildbucket for the build status
[10:16:16.096] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:16.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:30:0) after 1m0s
[10:17:16.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:30:0)
[10:17:16.182] Timer tick, asking Buildbucket for the build status
[10:17:16.311] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:16.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:31:0) after 1m0s
[10:18:16.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:31:0)
[10:18:16.619] Timer tick, asking Buildbucket for the build status
[10:18:17.265] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:17.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:32:0) after 1m0s
[10:19:17.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:32:0)
[10:19:17.421] Timer tick, asking Buildbucket for the build status
[10:19:17.617] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:17.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:33:0) after 1m0s
[10:20:17.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:33:0)
[10:20:17.888] Timer tick, asking Buildbucket for the build status
[10:20:18.106] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:18.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:34:0) after 1m0s
[10:21:18.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:34:0)
[10:21:18.299] Timer tick, asking Buildbucket for the build status
[10:21:18.634] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:18.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:35:0) after 1m0s
[10:22:18.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:35:0)
[10:22:18.741] Timer tick, asking Buildbucket for the build status
[10:22:18.825] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:18.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:36:0) after 1m0s
[10:23:18.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:36:0)
[10:23:18.971] Timer tick, asking Buildbucket for the build status
[10:23:19.041] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:19.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:37:0) after 1m0s
[10:24:19.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:37:0)
[10:24:19.440] Timer tick, asking Buildbucket for the build status
[10:24:19.699] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:19.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:38:0) after 1m0s
[10:25:19.874] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:38:0)
[10:25:19.874] Timer tick, asking Buildbucket for the build status
[10:25:20.141] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:20.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:39:0) after 1m0s
[10:26:20.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:39:0)
[10:26:20.231] Timer tick, asking Buildbucket for the build status
[10:26:20.421] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:20.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:40:0) after 1m0s
[10:27:20.508] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:40:0)
[10:27:20.508] Timer tick, asking Buildbucket for the build status
[10:27:20.798] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:20.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:41:0) after 1m0s
[10:28:20.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:41:0)
[10:28:20.880] Timer tick, asking Buildbucket for the build status
[10:28:21.004] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:21.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:42:0) after 1m0s
[10:29:21.049] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:42:0)
[10:29:21.049] Timer tick, asking Buildbucket for the build status
[10:29:21.459] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:21.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:43:0) after 1m0s
[10:30:21.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:43:0)
[10:30:21.531] Timer tick, asking Buildbucket for the build status
[10:30:21.750] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:21.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:44:0) after 1m0s
[10:31:21.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:44:0)
[10:31:21.777] Timer tick, asking Buildbucket for the build status
[10:31:21.997] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:21.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:45:0) after 1m0s
[10:32:22.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:45:0)
[10:32:22.062] Timer tick, asking Buildbucket for the build status
[10:32:22.206] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:22.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:46:0) after 1m0s
[10:33:22.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:46:0)
[10:33:22.231] Timer tick, asking Buildbucket for the build status
[10:33:22.491] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:22.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:47:0) after 1m0s
[10:34:22.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:47:0)
[10:34:22.509] Timer tick, asking Buildbucket for the build status
[10:34:22.664] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:22.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:48:0) after 1m0s
[10:35:22.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:48:0)
[10:35:22.758] Timer tick, asking Buildbucket for the build status
[10:35:22.974] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:22.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:49:0) after 1m0s
[10:36:23.044] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:49:0)
[10:36:23.044] Timer tick, asking Buildbucket for the build status
[10:36:23.153] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:23.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:50:0) after 1m0s
[10:37:23.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:50:0)
[10:37:23.241] Timer tick, asking Buildbucket for the build status
[10:37:23.317] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:23.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:51:0) after 1m0s
[10:38:23.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:51:0)
[10:38:23.307] Timer tick, asking Buildbucket for the build status
[10:38:23.408] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:23.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:52:0) after 1m0s
[10:39:23.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:52:0)
[10:39:23.469] Timer tick, asking Buildbucket for the build status
[10:39:23.868] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:23.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:53:0) after 1m0s
[10:40:23.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:53:0)
[10:40:23.920] Timer tick, asking Buildbucket for the build status
[10:40:24.003] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:24.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:54:0) after 1m0s
[10:41:24.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:54:0)
[10:41:24.069] Timer tick, asking Buildbucket for the build status
[10:41:24.216] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:24.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:55:0) after 1m0s
[10:42:24.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:55:0)
[10:42:24.241] Timer tick, asking Buildbucket for the build status
[10:42:24.418] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:24.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:56:0) after 1m0s
[10:43:24.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:56:0)
[10:43:24.458] Timer tick, asking Buildbucket for the build status
[10:43:24.738] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:24.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:57:0) after 1m0s
[10:44:24.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:57:0)
[10:44:24.811] Timer tick, asking Buildbucket for the build status
[10:44:24.989] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:24.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:58:0) after 1m0s
[10:45:25.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:58:0)
[10:45:25.042] Timer tick, asking Buildbucket for the build status
[10:45:25.511] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:25.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:59:0) after 1m0s
[10:46:25.635] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:59:0)
[10:46:25.635] Timer tick, asking Buildbucket for the build status
[10:46:25.796] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:25.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:60:0) after 1m0s
[10:47:25.826] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:60:0)
[10:47:25.826] Timer tick, asking Buildbucket for the build status
[10:47:26.036] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:26.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:61:0) after 1m0s
[10:48:26.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:61:0)
[10:48:26.083] Timer tick, asking Buildbucket for the build status
[10:48:26.176] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:26.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:62:0) after 1m0s
[10:49:26.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:62:0)
[10:49:26.214] Timer tick, asking Buildbucket for the build status
[10:49:26.358] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:26.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:63:0) after 1m0s
[10:50:26.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:63:0)
[10:50:26.402] Timer tick, asking Buildbucket for the build status
[10:50:26.525] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:26.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:64:0) after 1m0s
[10:51:26.511] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:64:0)
[10:51:26.511] Timer tick, asking Buildbucket for the build status
[10:51:26.581] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:26.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:65:0) after 1m0s
[10:52:26.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:65:0)
[10:52:26.604] Timer tick, asking Buildbucket for the build status
[10:52:26.718] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:26.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:66:0) after 1m0s
[10:53:26.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:66:0)
[10:53:26.746] Timer tick, asking Buildbucket for the build status
[10:53:26.999] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:26.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:67:0) after 1m0s
[10:54:27.047] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:67:0)
[10:54:27.047] Timer tick, asking Buildbucket for the build status
[10:54:27.212] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:27.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:68:0) after 1m0s
[10:55:27.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:68:0)
[10:55:27.236] Timer tick, asking Buildbucket for the build status
[10:55:27.350] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:27.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:69:0) after 1m0s
[10:56:27.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:69:0)
[10:56:27.423] Timer tick, asking Buildbucket for the build status
[10:56:27.581] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:27.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:70:0) after 1m0s
[10:57:27.610] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:70:0)
[10:57:27.610] Timer tick, asking Buildbucket for the build status
[10:57:27.687] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:27.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:71:0) after 1m0s
[10:58:27.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:71:0)
[10:58:27.717] Timer tick, asking Buildbucket for the build status
[10:58:27.826] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:27.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:72:0) after 1m0s
[10:59:27.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:72:0)
[10:59:27.854] Timer tick, asking Buildbucket for the build status
[10:59:27.970] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:27.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:73:0) after 1m0s
[11:00:28.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:73:0)
[11:00:28.000] Timer tick, asking Buildbucket for the build status
[11:00:28.117] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:28.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:74:0) after 1m0s
[11:01:28.213] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:74:0)
[11:01:28.213] Timer tick, asking Buildbucket for the build status
[11:01:28.296] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:28.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:75:0) after 1m0s
[11:02:28.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:75:0)
[11:02:28.293] Timer tick, asking Buildbucket for the build status
[11:02:28.502] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:28.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:76:0) after 1m0s
[11:03:28.581] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:76:0)
[11:03:28.581] Timer tick, asking Buildbucket for the build status
[11:03:28.690] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:28.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:77:0) after 1m0s
[11:04:28.718] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:77:0)
[11:04:28.718] Timer tick, asking Buildbucket for the build status
[11:04:28.873] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:28.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:78:0) after 1m0s
[11:05:28.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:78:0)
[11:05:28.921] Timer tick, asking Buildbucket for the build status
[11:05:29.002] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:29.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:79:0) after 1m0s
[11:06:29.049] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:79:0)
[11:06:29.049] Timer tick, asking Buildbucket for the build status
[11:06:29.164] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:29.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:80:0) after 1m0s
[11:07:29.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:80:0)
[11:07:29.283] Timer tick, asking Buildbucket for the build status
[11:07:29.385] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:29.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:81:0) after 1m0s
[11:08:29.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:81:0)
[11:08:29.414] Timer tick, asking Buildbucket for the build status
[11:08:30.712] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:30.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:82:0) after 1m0s
[11:09:30.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:82:0)
[11:09:30.780] Timer tick, asking Buildbucket for the build status
[11:09:30.900] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:30.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:83:0) after 1m0s
[11:10:31.004] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:83:0)
[11:10:31.004] Timer tick, asking Buildbucket for the build status
[11:10:31.072] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:31.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:84:0) after 1m0s
[11:11:31.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:84:0)
[11:11:31.260] Timer tick, asking Buildbucket for the build status
[11:11:31.326] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:31.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:85:0) after 1m0s
[11:12:31.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:85:0)
[11:12:31.475] Timer tick, asking Buildbucket for the build status
[11:12:31.574] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:31.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:86:0) after 1m0s
[11:13:31.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:86:0)
[11:13:31.614] Timer tick, asking Buildbucket for the build status
[11:13:31.994] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:31.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:87:0) after 1m0s
[11:14:32.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:87:0)
[11:14:32.058] Timer tick, asking Buildbucket for the build status
[11:14:32.556] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:32.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:88:0) after 1m0s
[11:15:32.705] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:88:0)
[11:15:32.705] Timer tick, asking Buildbucket for the build status
[11:15:32.816] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:32.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:89:0) after 1m0s
[11:16:32.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:89:0)
[11:16:32.933] Timer tick, asking Buildbucket for the build status
[11:16:33.044] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:33.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:90:0) after 1m0s
[11:17:33.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:90:0)
[11:17:33.075] Timer tick, asking Buildbucket for the build status
[11:17:33.412] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:33.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:91:0) after 1m0s
[11:18:33.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:91:0)
[11:18:33.510] Timer tick, asking Buildbucket for the build status
[11:18:34.143] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:34.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:92:0) after 1m0s
[11:19:34.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:92:0)
[11:19:34.174] Timer tick, asking Buildbucket for the build status
[11:19:34.925] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:34.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:93:0) after 1m0s
[11:20:35.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:93:0)
[11:20:35.062] Timer tick, asking Buildbucket for the build status
[11:20:35.162] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:35.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:94:0) after 1m0s
[11:21:35.206] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:94:0)
[11:21:35.206] Timer tick, asking Buildbucket for the build status
[11:21:35.335] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:35.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:95:0) after 1m0s
[11:22:35.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:95:0)
[11:22:35.401] Timer tick, asking Buildbucket for the build status
[11:22:35.481] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:35.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:96:0) after 1m0s
[11:23:35.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:96:0)
[11:23:35.518] Timer tick, asking Buildbucket for the build status
[11:23:35.778] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:35.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:97:0) after 1m0s
[11:24:35.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:97:0)
[11:24:35.802] Timer tick, asking Buildbucket for the build status
[11:24:36.063] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:36.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:98:0) after 1m0s
[11:25:36.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:98:0)
[11:25:36.102] Timer tick, asking Buildbucket for the build status
[11:25:36.228] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:36.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:99:0) after 1m0s
[11:26:36.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:99:0)
[11:26:36.275] Timer tick, asking Buildbucket for the build status
[11:26:36.464] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:36.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:100:0) after 1m0s
[11:27:36.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:100:0)
[11:27:36.529] Timer tick, asking Buildbucket for the build status
[11:27:36.632] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:36.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:101:0) after 1m0s
[11:28:36.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:101:0)
[11:28:36.672] Timer tick, asking Buildbucket for the build status
[11:28:36.783] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:36.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:102:0) after 1m0s
[11:29:36.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:102:0)
[11:29:36.844] Timer tick, asking Buildbucket for the build status
[11:29:37.020] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:37.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:103:0) after 1m0s
[11:30:37.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:103:0)
[11:30:37.015] Timer tick, asking Buildbucket for the build status
[11:30:37.136] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:37.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:104:0) after 1m0s
[11:31:37.166] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:104:0)
[11:31:37.166] Timer tick, asking Buildbucket for the build status
[11:31:37.251] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:37.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:105:0) after 1m0s
[11:32:37.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:105:0)
[11:32:37.309] Timer tick, asking Buildbucket for the build status
[11:32:37.381] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:37.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:106:0) after 1m0s
[11:33:37.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:106:0)
[11:33:37.413] Timer tick, asking Buildbucket for the build status
[11:33:37.524] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:37.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:107:0) after 1m0s
[11:34:37.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:107:0)
[11:34:37.548] Timer tick, asking Buildbucket for the build status
[11:34:37.708] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:37.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:108:0) after 1m0s
[11:35:37.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:108:0)
[11:35:37.738] Timer tick, asking Buildbucket for the build status
[11:35:37.867] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:37.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:109:0) after 1m0s
[11:36:37.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:109:0)
[11:36:37.912] Timer tick, asking Buildbucket for the build status
[11:36:37.970] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:37.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:110:0) after 1m0s
[11:37:38.098] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:110:0)
[11:37:38.098] Timer tick, asking Buildbucket for the build status
[11:37:38.307] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:38.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:111:0) after 1m0s
[11:38:38.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:111:0)
[11:38:38.366] Timer tick, asking Buildbucket for the build status
[11:38:38.618] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:38.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:112:0) after 1m0s
[11:39:38.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:112:0)
[11:39:38.742] Timer tick, asking Buildbucket for the build status
[11:39:38.920] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:38.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:113:0) after 1m0s
[11:40:39.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:113:0)
[11:40:39.016] Timer tick, asking Buildbucket for the build status
[11:40:39.135] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:39.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:114:0) after 1m0s
[11:41:39.240] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:114:0)
[11:41:39.240] Timer tick, asking Buildbucket for the build status
[11:41:39.497] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:39.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:115:0) after 1m0s
[11:42:39.625] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:115:0)
[11:42:39.625] Timer tick, asking Buildbucket for the build status
[11:42:39.736] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:39.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:116:0) after 1m0s
[11:43:39.838] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:116:0)
[11:43:39.838] Timer tick, asking Buildbucket for the build status
[11:43:39.985] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:39.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:117:0) after 1m0s
[11:44:40.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:117:0)
[11:44:40.101] Timer tick, asking Buildbucket for the build status
[11:44:40.325] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:40.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:118:0) after 1m0s
[11:45:40.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:118:0)
[11:45:40.410] Timer tick, asking Buildbucket for the build status
[11:45:40.496] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:40.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:119:0) after 1m0s
[11:46:40.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:119:0)
[11:46:40.517] Timer tick, asking Buildbucket for the build status
[11:46:40.629] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:40.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:120:0) after 1m0s
[11:47:40.683] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:120:0)
[11:47:40.683] Timer tick, asking Buildbucket for the build status
[11:47:41.156] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:41.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:121:0) after 1m0s
[11:48:41.188] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:121:0)
[11:48:41.188] Timer tick, asking Buildbucket for the build status
[11:48:41.314] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:41.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:122:0) after 1m0s
[11:49:41.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:122:0)
[11:49:41.367] Timer tick, asking Buildbucket for the build status
[11:49:41.649] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:41.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:123:0) after 1m0s
[11:50:41.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:123:0)
[11:50:41.674] Timer tick, asking Buildbucket for the build status
[11:50:41.761] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:41.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:124:0) after 1m0s
[11:51:41.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:124:0)
[11:51:41.800] Timer tick, asking Buildbucket for the build status
[11:51:41.927] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:41.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:125:0) after 1m0s
[11:52:41.959] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:125:0)
[11:52:41.959] Timer tick, asking Buildbucket for the build status
[11:52:42.041] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:42.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:126:0) after 1m0s
[11:53:42.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:126:0)
[11:53:42.076] Timer tick, asking Buildbucket for the build status
[11:53:42.709] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:42.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:127:0) after 1m0s
[11:54:42.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:127:0)
[11:54:42.756] Timer tick, asking Buildbucket for the build status
[11:54:44.969] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:44.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:128:0) after 1m0s
[11:55:45.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:128:0)
[11:55:45.034] Timer tick, asking Buildbucket for the build status
[11:55:45.088] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:45.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:129:0) after 1m0s
[11:56:45.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:129:0)
[11:56:45.155] Timer tick, asking Buildbucket for the build status
[11:56:45.360] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:45.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:130:0) after 1m0s
[11:57:45.468] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:130:0)
[11:57:45.468] Timer tick, asking Buildbucket for the build status
[11:57:45.536] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:45.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:131:0) after 1m0s
[11:58:45.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:131:0)
[11:58:45.642] Timer tick, asking Buildbucket for the build status
[11:58:45.736] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:45.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:132:0) after 1m0s
[11:59:45.826] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:132:0)
[11:59:45.826] Timer tick, asking Buildbucket for the build status
[11:59:46.090] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:46.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:133:0) after 1m0s
[12:00:46.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:133:0)
[12:00:46.223] Timer tick, asking Buildbucket for the build status
[12:00:46.330] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:46.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:134:0) after 1m0s
[12:01:46.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:134:0)
[12:01:46.389] Timer tick, asking Buildbucket for the build status
[12:01:46.589] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:46.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:135:0) after 1m0s
[12:02:46.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:135:0)
[12:02:46.590] Timer tick, asking Buildbucket for the build status
[12:02:47.434] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:47.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:136:0) after 1m0s
[12:03:47.479] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:136:0)
[12:03:47.479] Timer tick, asking Buildbucket for the build status
[12:03:47.558] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:47.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:137:0) after 1m0s
[12:04:47.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:137:0)
[12:04:47.721] Timer tick, asking Buildbucket for the build status
[12:04:47.909] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:47.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:138:0) after 1m0s
[12:05:48.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:138:0)
[12:05:48.058] Timer tick, asking Buildbucket for the build status
[12:05:48.145] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:48.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:139:0) after 1m0s
[12:06:48.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:139:0)
[12:06:48.264] Timer tick, asking Buildbucket for the build status
[12:06:48.361] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:48.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:140:0) after 1m0s
[12:07:48.443] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:140:0)
[12:07:48.443] Timer tick, asking Buildbucket for the build status
[12:07:48.529] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:48.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:141:0) after 1m0s
[12:08:48.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:141:0)
[12:08:48.621] Timer tick, asking Buildbucket for the build status
[12:08:49.174] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:49.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:142:0) after 1m0s
[12:09:49.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:142:0)
[12:09:49.266] Timer tick, asking Buildbucket for the build status
[12:09:49.799] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:49.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:143:0) after 1m0s
[12:10:49.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:143:0)
[12:10:49.900] Timer tick, asking Buildbucket for the build status
[12:10:50.120] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:50.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:144:0) after 1m0s
[12:11:50.232] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:144:0)
[12:11:50.232] Timer tick, asking Buildbucket for the build status
[12:11:50.414] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:50.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:145:0) after 1m0s
[12:12:50.617] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:145:0)
[12:12:50.617] Timer tick, asking Buildbucket for the build status
[12:12:50.760] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:50.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:146:0) after 1m0s
[12:13:51.063] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:146:0)
[12:13:51.063] Timer tick, asking Buildbucket for the build status
[12:13:51.220] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:51.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:147:0) after 1m0s
[12:14:51.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:147:0)
[12:14:51.362] Timer tick, asking Buildbucket for the build status
[12:14:51.683] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:51.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:148:0) after 1m0s
[12:15:51.944] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:148:0)
[12:15:51.944] Timer tick, asking Buildbucket for the build status
[12:15:52.051] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:52.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:149:0) after 1m0s
[12:16:52.290] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:149:0)
[12:16:52.290] Timer tick, asking Buildbucket for the build status
[12:16:52.519] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:52.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:150:0) after 1m0s
[12:17:52.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:150:0)
[12:17:52.568] Timer tick, asking Buildbucket for the build status
[12:17:52.746] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:52.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:151:0) after 1m0s
[12:18:52.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:151:0)
[12:18:52.836] Timer tick, asking Buildbucket for the build status
[12:18:52.940] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:52.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:152:0) after 1m0s
[12:19:52.975] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:152:0)
[12:19:52.975] Timer tick, asking Buildbucket for the build status
[12:19:53.245] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:53.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:153:0) after 1m0s
[12:20:53.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:153:0)
[12:20:53.307] Timer tick, asking Buildbucket for the build status
[12:20:53.525] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:53.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:154:0) after 1m0s
[12:21:53.577] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:154:0)
[12:21:53.577] Timer tick, asking Buildbucket for the build status
[12:21:53.708] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:53.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:155:0) after 1m0s
[12:22:53.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:155:0)
[12:22:53.805] Timer tick, asking Buildbucket for the build status
[12:22:53.933] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:53.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:156:0) after 1m0s
[12:23:53.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:156:0)
[12:23:53.968] Timer tick, asking Buildbucket for the build status
[12:23:54.068] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:54.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:157:0) after 1m0s
[12:24:54.148] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:157:0)
[12:24:54.148] Timer tick, asking Buildbucket for the build status
[12:24:54.316] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:54.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:158:0) after 1m0s
[12:25:54.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:158:0)
[12:25:54.400] Timer tick, asking Buildbucket for the build status
[12:25:54.510] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:54.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:159:0) after 1m0s
[12:26:54.543] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:159:0)
[12:26:54.543] Timer tick, asking Buildbucket for the build status
[12:26:54.621] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:54.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:160:0) after 1m0s
[12:27:54.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:160:0)
[12:27:54.694] Timer tick, asking Buildbucket for the build status
[12:27:54.787] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:54.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:161:0) after 1m0s
[12:28:54.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:161:0)
[12:28:54.851] Timer tick, asking Buildbucket for the build status
[12:28:54.976] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:54.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:162:0) after 1m0s
[12:29:55.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:162:0)
[12:29:55.064] Timer tick, asking Buildbucket for the build status
[12:29:55.242] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:55.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:163:0) after 1m0s
[12:30:55.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:163:0)
[12:30:55.295] Timer tick, asking Buildbucket for the build status
[12:30:55.576] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:55.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:164:0) after 1m0s
[12:31:55.716] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:164:0)
[12:31:55.716] Timer tick, asking Buildbucket for the build status
[12:31:56.274] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:56.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:165:0) after 1m0s
[12:32:56.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:165:0)
[12:32:56.412] Timer tick, asking Buildbucket for the build status
[12:32:56.517] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:56.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:166:0) after 1m0s
[12:33:56.566] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:166:0)
[12:33:56.566] Timer tick, asking Buildbucket for the build status
[12:33:56.642] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:56.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:167:0) after 1m0s
[12:34:56.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:167:0)
[12:34:56.665] Timer tick, asking Buildbucket for the build status
[12:34:56.849] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:56.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:168:0) after 1m0s
[12:35:56.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:168:0)
[12:35:56.912] Timer tick, asking Buildbucket for the build status
[12:35:57.078] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:57.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:169:0) after 1m0s
[12:36:57.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:169:0)
[12:36:57.122] Timer tick, asking Buildbucket for the build status
[12:36:57.188] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:57.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:170:0) after 1m0s
[12:37:57.284] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:170:0)
[12:37:57.284] Timer tick, asking Buildbucket for the build status
[12:37:57.439] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:57.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:171:0) after 1m0s
[12:38:57.557] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:171:0)
[12:38:57.557] Timer tick, asking Buildbucket for the build status
[12:38:57.681] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:57.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:172:0) after 1m0s
[12:39:57.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:172:0)
[12:39:57.770] Timer tick, asking Buildbucket for the build status
[12:39:57.967] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:57.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:173:0) after 1m0s
[12:40:57.996] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:173:0)
[12:40:57.996] Timer tick, asking Buildbucket for the build status
[12:40:58.275] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:58.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:174:0) after 1m0s
[12:41:58.319] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:174:0)
[12:41:58.319] Timer tick, asking Buildbucket for the build status
[12:41:58.390] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:58.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:175:0) after 1m0s
[12:42:58.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:175:0)
[12:42:58.505] Timer tick, asking Buildbucket for the build status
[12:42:58.683] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:58.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:176:0) after 1m0s
[12:43:58.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:176:0)
[12:43:58.721] Timer tick, asking Buildbucket for the build status
[12:43:58.895] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:58.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:177:0) after 1m0s
[12:44:58.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:177:0)
[12:44:58.927] Timer tick, asking Buildbucket for the build status
[12:44:59.227] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:59.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:178:0) after 1m0s
[12:45:59.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:178:0)
[12:45:59.304] Timer tick, asking Buildbucket for the build status
[12:45:59.517] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:59.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:179:0) after 1m0s
[12:46:59.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:179:0)
[12:46:59.601] Timer tick, asking Buildbucket for the build status
[12:46:59.894] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:59.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:180:0) after 1m0s
[12:47:59.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:180:0)
[12:47:59.912] Timer tick, asking Buildbucket for the build status
[12:48:00.197] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:00.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:181:0) after 1m0s
[12:49:00.390] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:181:0)
[12:49:00.390] Timer tick, asking Buildbucket for the build status
[12:49:00.500] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:00.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:182:0) after 1m0s
[12:50:01.073] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:182:0)
[12:50:01.073] Timer tick, asking Buildbucket for the build status
[12:50:01.263] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:01.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:183:0) after 1m0s
[12:51:02.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:183:0)
[12:51:02.106] Timer tick, asking Buildbucket for the build status
[12:51:02.302] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:02.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:184:0) after 1m0s
[12:52:02.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:184:0)
[12:52:02.434] Timer tick, asking Buildbucket for the build status
[12:52:02.651] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:02.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:185:0) after 1m0s
[12:53:02.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:185:0)
[12:53:02.748] Timer tick, asking Buildbucket for the build status
[12:53:02.886] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:02.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:186:0) after 1m0s
[12:54:02.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:186:0)
[12:54:02.957] Timer tick, asking Buildbucket for the build status
[12:54:03.123] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:03.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:187:0) after 1m0s
[12:55:03.285] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:187:0)
[12:55:03.285] Timer tick, asking Buildbucket for the build status
[12:55:03.417] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:03.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:188:0) after 1m0s
[12:56:03.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:188:0)
[12:56:03.516] Timer tick, asking Buildbucket for the build status
[12:56:03.612] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:03.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:189:0) after 1m0s
[12:57:03.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:189:0)
[12:57:03.854] Timer tick, asking Buildbucket for the build status
[12:57:03.964] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:03.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:190:0) after 1m0s
[12:58:04.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:190:0)
[12:58:04.201] Timer tick, asking Buildbucket for the build status
[12:58:04.417] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:04.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:191:0) after 1m0s
[12:59:04.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:191:0)
[12:59:04.532] Timer tick, asking Buildbucket for the build status
[12:59:04.638] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:04.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:192:0) after 1m0s
[13:00:04.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:192:0)
[13:00:04.736] Timer tick, asking Buildbucket for the build status
[13:00:04.939] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:04.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:193:0) after 1m0s
[13:01:05.089] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:193:0)
[13:01:05.090] Timer tick, asking Buildbucket for the build status
[13:01:06.097] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:06.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:194:0) after 1m0s
[13:02:06.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:194:0)
[13:02:06.145] Timer tick, asking Buildbucket for the build status
[13:02:06.389] Build 8872181132269158192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:06.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037611198308408384:195:0) after 1m0s
[13:02:14.701] Received PubSub notification, asking Buildbucket for the build status
[13:02:14.801] Build 8872181132269158192: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:02:14.801] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597237333908426",
"created_by": "project:chromium",
"created_ts": "1597225745858734",
"id": "8872181132269158192",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"77a5bff862df162ec25b1b5164deada040e0f3ce\"}}",
"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-4-74dv\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 322, \"got_angle_revision\": \"d667ad2f0127117e6a30057b09810dc0cce261e9\", \"got_dawn_revision\": \"37f547456cc61ba30bb27a60a18cbbdf1cd0d7e5\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"77a5bff862df162ec25b1b5164deada040e0f3ce\", \"got_revision_cp\": \"refs/heads/master@{#797172}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"13f04d59c7278ac87191465bc16e2c7d145ae851\", \"got_v8_revision\": \"347e854b713ba7e4d782ae008fa116ceb8ba3687\", \"got_v8_revision_cp\": \"refs/heads/8.6.350@{#1}\", \"got_webrtc_revision\": \"c2cfd18ab8cabb29fe0866d467588d22b8c5e501\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31914}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"77a5bff862df162ec25b1b5164deada040e0f3ce\"}, \"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-4-74dv\"], \"image\": [\"chrome-win10-1703-20073100-2eb11ddbc6e\"], \"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\": [\"5480-a98aa12\"], \"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": "1597225878949809",
"status": "COMPLETED",
"status_changed_ts": "1597237334344580",
"tags": [
"build_address:luci.chromium.ci/win-official/322",
"builder:win-official",
"buildset:commit/git/77a5bff862df162ec25b1b5164deada040e0f3ce",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77a5bff862df162ec25b1b5164deada040e0f3ce",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037611198308408384",
"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/8872181132269158192/+/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:4dfae448aca55b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1597237334350318",
"url": "https://ci.chromium.org/b/8872181132269158192",
"utcnow_ts": "1597237334791718"
}
}
[13:02:14.801] Invocation finished in 3h13m14.086747746s with status SUCCEEDED