[06:39:56.611] New invocation is queued and will start shortly
[06:39:57.707] Starting the invocation (attempt 1)
[06:39:57.793] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:39:57.793] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:39:57.793] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9036173542022453904",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0c0d4a164f0dd1fe9ebb45cfabfec8357522e345\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9036173542022453904",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/0c0d4a164f0dd1fe9ebb45cfabfec8357522e345",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c0d4a164f0dd1fe9ebb45cfabfec8357522e345",
"gitiles_ref:refs/heads/master"
]
}
[06:39:59.216] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1598596797901303",
"id": "8870743480002909200",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c0d4a164f0dd1fe9ebb45cfabfec8357522e345\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1598596798845666",
"tags": [
"build_address:luci.chromium.ci/win-official/441",
"builder:win-official",
"buildset:commit/git/0c0d4a164f0dd1fe9ebb45cfabfec8357522e345",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c0d4a164f0dd1fe9ebb45cfabfec8357522e345",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036173542022453904",
"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/8870743480002909200/+/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": "1598596798846208",
"url": "https://ci.chromium.org/b/8870743480002909200",
"utcnow_ts": "1598596799198138"
}
}
[06:39:59.216] Task URL: https://ci.chromium.org/b/8870743480002909200
[06:39:59.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:2:0) after 1m0s
[06:40:40.854] Received PubSub notification, asking Buildbucket for the build status
[06:40:40.942] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:59.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:2:0)
[06:40:59.239] Timer tick, asking Buildbucket for the build status
[06:40:59.340] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:59.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:4:0) after 1m0s
[06:41:59.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:4:0)
[06:41:59.383] Timer tick, asking Buildbucket for the build status
[06:41:59.606] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:59.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:5:0) after 1m0s
[06:42:59.683] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:5:0)
[06:42:59.683] Timer tick, asking Buildbucket for the build status
[06:42:59.890] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:59.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:6:0) after 1m0s
[06:43:59.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:6:0)
[06:43:59.981] Timer tick, asking Buildbucket for the build status
[06:44:00.217] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:00.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:7:0) after 1m0s
[06:45:00.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:7:0)
[06:45:00.314] Timer tick, asking Buildbucket for the build status
[06:45:00.396] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:00.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:8:0) after 1m0s
[06:46:00.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:8:0)
[06:46:00.588] Timer tick, asking Buildbucket for the build status
[06:46:00.701] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:00.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:9:0) after 1m0s
[06:47:00.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:9:0)
[06:47:00.834] Timer tick, asking Buildbucket for the build status
[06:47:00.924] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:00.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:10:0) after 1m0s
[06:48:00.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:10:0)
[06:48:00.945] Timer tick, asking Buildbucket for the build status
[06:48:01.256] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:01.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:11:0) after 1m0s
[06:49:01.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:11:0)
[06:49:01.423] Timer tick, asking Buildbucket for the build status
[06:49:01.548] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:01.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:12:0) after 1m0s
[06:50:02.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:12:0)
[06:50:02.013] Timer tick, asking Buildbucket for the build status
[06:50:02.150] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:02.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:13:0) after 1m0s
[06:51:02.197] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:13:0)
[06:51:02.197] Timer tick, asking Buildbucket for the build status
[06:51:02.682] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:02.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:14:0) after 1m0s
[06:52:02.843] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:14:0)
[06:52:02.843] Timer tick, asking Buildbucket for the build status
[06:52:02.919] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:02.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:15:0) after 1m0s
[06:53:02.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:15:0)
[06:53:02.948] Timer tick, asking Buildbucket for the build status
[06:53:03.021] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:03.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:16:0) after 1m0s
[06:54:03.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:16:0)
[06:54:03.086] Timer tick, asking Buildbucket for the build status
[06:54:03.158] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:03.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:17:0) after 1m0s
[06:55:03.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:17:0)
[06:55:03.209] Timer tick, asking Buildbucket for the build status
[06:55:03.453] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:03.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:18:0) after 1m0s
[06:56:03.577] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:18:0)
[06:56:03.577] Timer tick, asking Buildbucket for the build status
[06:56:03.742] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:03.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:19:0) after 1m0s
[06:57:03.810] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:19:0)
[06:57:03.810] Timer tick, asking Buildbucket for the build status
[06:57:03.887] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:03.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:20:0) after 1m0s
[06:58:04.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:20:0)
[06:58:04.019] Timer tick, asking Buildbucket for the build status
[06:58:04.771] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:04.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:21:0) after 1m0s
[06:59:04.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:21:0)
[06:59:04.898] Timer tick, asking Buildbucket for the build status
[06:59:05.232] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:05.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:22:0) after 1m0s
[07:00:05.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:22:0)
[07:00:05.287] Timer tick, asking Buildbucket for the build status
[07:00:05.848] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:05.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:23:0) after 1m0s
[07:01:05.995] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:23:0)
[07:01:05.995] Timer tick, asking Buildbucket for the build status
[07:01:06.143] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:06.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:24:0) after 1m0s
[07:02:06.493] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:24:0)
[07:02:06.493] Timer tick, asking Buildbucket for the build status
[07:02:06.991] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:06.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:25:0) after 1m0s
[07:03:07.191] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:25:0)
[07:03:07.191] Timer tick, asking Buildbucket for the build status
[07:03:07.253] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:07.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:26:0) after 1m0s
[07:04:07.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:26:0)
[07:04:07.384] Timer tick, asking Buildbucket for the build status
[07:04:07.793] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:07.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:27:0) after 1m0s
[07:05:07.869] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:27:0)
[07:05:07.869] Timer tick, asking Buildbucket for the build status
[07:05:08.024] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:08.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:28:0) after 1m0s
[07:06:08.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:28:0)
[07:06:08.626] Timer tick, asking Buildbucket for the build status
[07:06:08.727] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:08.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:29:0) after 1m0s
[07:07:08.813] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:29:0)
[07:07:08.813] Timer tick, asking Buildbucket for the build status
[07:07:08.903] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:08.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:30:0) after 1m0s
[07:08:08.929] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:30:0)
[07:08:08.929] Timer tick, asking Buildbucket for the build status
[07:08:09.019] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:09.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:31:0) after 1m0s
[07:09:09.054] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:31:0)
[07:09:09.054] Timer tick, asking Buildbucket for the build status
[07:09:09.283] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:09.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:32:0) after 1m0s
[07:10:09.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:32:0)
[07:10:09.313] Timer tick, asking Buildbucket for the build status
[07:10:10.418] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:10.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:33:0) after 1m0s
[07:11:10.474] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:33:0)
[07:11:10.474] Timer tick, asking Buildbucket for the build status
[07:11:10.779] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:10.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:34:0) after 1m0s
[07:12:10.769] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:34:0)
[07:12:10.769] Timer tick, asking Buildbucket for the build status
[07:12:10.867] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:10.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:35:0) after 1m0s
[07:13:11.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:35:0)
[07:13:11.033] Timer tick, asking Buildbucket for the build status
[07:13:11.197] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:11.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:36:0) after 1m0s
[07:14:11.284] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:36:0)
[07:14:11.284] Timer tick, asking Buildbucket for the build status
[07:14:11.529] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:11.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:37:0) after 1m0s
[07:15:11.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:37:0)
[07:15:11.565] Timer tick, asking Buildbucket for the build status
[07:15:11.789] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:11.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:38:0) after 1m0s
[07:16:11.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:38:0)
[07:16:11.846] Timer tick, asking Buildbucket for the build status
[07:16:11.987] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:11.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:39:0) after 1m0s
[07:17:12.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:39:0)
[07:17:12.017] Timer tick, asking Buildbucket for the build status
[07:17:12.181] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:12.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:40:0) after 1m0s
[07:18:12.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:40:0)
[07:18:12.259] Timer tick, asking Buildbucket for the build status
[07:18:12.645] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:12.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:41:0) after 1m0s
[07:19:12.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:41:0)
[07:19:12.744] Timer tick, asking Buildbucket for the build status
[07:19:12.859] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:12.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:42:0) after 1m0s
[07:20:13.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:42:0)
[07:20:13.077] Timer tick, asking Buildbucket for the build status
[07:20:13.184] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:13.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:43:0) after 1m0s
[07:21:13.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:43:0)
[07:21:13.357] Timer tick, asking Buildbucket for the build status
[07:21:13.598] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:13.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:44:0) after 1m0s
[07:22:13.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:44:0)
[07:22:13.692] Timer tick, asking Buildbucket for the build status
[07:22:13.797] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:13.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:45:0) after 1m0s
[07:23:13.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:45:0)
[07:23:13.890] Timer tick, asking Buildbucket for the build status
[07:23:13.970] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:13.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:46:0) after 1m0s
[07:24:14.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:46:0)
[07:24:14.083] Timer tick, asking Buildbucket for the build status
[07:24:14.361] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:14.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:47:0) after 1m0s
[07:25:14.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:47:0)
[07:25:14.423] Timer tick, asking Buildbucket for the build status
[07:25:14.738] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:14.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:48:0) after 1m0s
[07:26:14.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:48:0)
[07:26:14.903] Timer tick, asking Buildbucket for the build status
[07:26:15.032] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:15.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:49:0) after 1m0s
[07:27:15.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:49:0)
[07:27:15.135] Timer tick, asking Buildbucket for the build status
[07:27:15.217] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:15.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:50:0) after 1m0s
[07:28:15.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:50:0)
[07:28:15.501] Timer tick, asking Buildbucket for the build status
[07:28:15.587] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:15.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:51:0) after 1m0s
[07:29:15.670] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:51:0)
[07:29:15.670] Timer tick, asking Buildbucket for the build status
[07:29:15.775] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:15.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:52:0) after 1m0s
[07:30:15.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:52:0)
[07:30:15.834] Timer tick, asking Buildbucket for the build status
[07:30:16.014] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:16.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:53:0) after 1m0s
[07:31:16.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:53:0)
[07:31:16.146] Timer tick, asking Buildbucket for the build status
[07:31:16.236] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:16.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:54:0) after 1m0s
[07:32:16.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:54:0)
[07:32:16.304] Timer tick, asking Buildbucket for the build status
[07:32:16.379] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:16.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:55:0) after 1m0s
[07:33:16.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:55:0)
[07:33:16.438] Timer tick, asking Buildbucket for the build status
[07:33:16.589] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:16.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:56:0) after 1m0s
[07:34:16.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:56:0)
[07:34:16.647] Timer tick, asking Buildbucket for the build status
[07:34:16.721] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:16.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:57:0) after 1m0s
[07:35:16.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:57:0)
[07:35:16.788] Timer tick, asking Buildbucket for the build status
[07:35:16.893] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:16.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:58:0) after 1m0s
[07:36:17.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:58:0)
[07:36:17.000] Timer tick, asking Buildbucket for the build status
[07:36:17.068] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:17.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:59:0) after 1m0s
[07:37:17.121] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:59:0)
[07:37:17.121] Timer tick, asking Buildbucket for the build status
[07:37:17.350] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:17.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:60:0) after 1m0s
[07:38:17.381] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:60:0)
[07:38:17.381] Timer tick, asking Buildbucket for the build status
[07:38:17.477] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:17.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:61:0) after 1m0s
[07:39:17.511] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:61:0)
[07:39:17.511] Timer tick, asking Buildbucket for the build status
[07:39:17.582] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:17.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:62:0) after 1m0s
[07:40:17.709] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:62:0)
[07:40:17.709] Timer tick, asking Buildbucket for the build status
[07:40:17.836] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:17.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:63:0) after 1m0s
[07:41:17.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:63:0)
[07:41:17.971] Timer tick, asking Buildbucket for the build status
[07:41:18.049] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:18.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:64:0) after 1m0s
[07:42:18.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:64:0)
[07:42:18.088] Timer tick, asking Buildbucket for the build status
[07:42:18.226] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:18.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:65:0) after 1m0s
[07:43:18.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:65:0)
[07:43:18.470] Timer tick, asking Buildbucket for the build status
[07:43:18.536] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:18.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:66:0) after 1m0s
[07:44:18.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:66:0)
[07:44:18.571] Timer tick, asking Buildbucket for the build status
[07:44:18.670] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:18.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:67:0) after 1m0s
[07:45:18.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:67:0)
[07:45:18.807] Timer tick, asking Buildbucket for the build status
[07:45:19.886] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:19.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:68:0) after 1m0s
[07:46:19.944] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:68:0)
[07:46:19.944] Timer tick, asking Buildbucket for the build status
[07:46:20.064] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:20.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:69:0) after 1m0s
[07:47:20.215] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:69:0)
[07:47:20.215] Timer tick, asking Buildbucket for the build status
[07:47:20.304] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:20.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:70:0) after 1m0s
[07:48:20.325] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:70:0)
[07:48:20.325] Timer tick, asking Buildbucket for the build status
[07:48:20.412] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:20.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:71:0) after 1m0s
[07:49:20.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:71:0)
[07:49:20.388] Timer tick, asking Buildbucket for the build status
[07:49:20.456] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:20.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:72:0) after 1m0s
[07:50:20.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:72:0)
[07:50:20.573] Timer tick, asking Buildbucket for the build status
[07:50:20.706] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:20.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:73:0) after 1m0s
[07:51:20.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:73:0)
[07:51:20.738] Timer tick, asking Buildbucket for the build status
[07:51:21.198] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:21.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:74:0) after 1m0s
[07:52:21.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:74:0)
[07:52:21.398] Timer tick, asking Buildbucket for the build status
[07:52:21.688] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:21.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:75:0) after 1m0s
[07:53:21.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:75:0)
[07:53:21.729] Timer tick, asking Buildbucket for the build status
[07:53:21.942] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:21.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:76:0) after 1m0s
[07:54:22.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:76:0)
[07:54:22.019] Timer tick, asking Buildbucket for the build status
[07:54:23.582] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:23.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:77:0) after 1m0s
[07:55:23.605] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:77:0)
[07:55:23.605] Timer tick, asking Buildbucket for the build status
[07:55:23.741] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:23.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:78:0) after 1m0s
[07:56:23.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:78:0)
[07:56:23.772] Timer tick, asking Buildbucket for the build status
[07:56:24.211] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:24.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:79:0) after 1m0s
[07:57:24.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:79:0)
[07:57:24.237] Timer tick, asking Buildbucket for the build status
[07:57:24.305] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:24.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:80:0) after 1m0s
[07:58:24.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:80:0)
[07:58:24.335] Timer tick, asking Buildbucket for the build status
[07:58:24.419] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:24.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:81:0) after 1m0s
[07:59:24.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:81:0)
[07:59:24.482] Timer tick, asking Buildbucket for the build status
[07:59:24.556] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:24.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:82:0) after 1m0s
[08:00:24.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:82:0)
[08:00:24.682] Timer tick, asking Buildbucket for the build status
[08:00:24.873] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:24.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:83:0) after 1m0s
[08:01:25.032] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:83:0)
[08:01:25.032] Timer tick, asking Buildbucket for the build status
[08:01:25.110] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:25.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:84:0) after 1m0s
[08:02:25.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:84:0)
[08:02:25.229] Timer tick, asking Buildbucket for the build status
[08:02:25.442] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:25.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:85:0) after 1m0s
[08:03:25.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:85:0)
[08:03:25.574] Timer tick, asking Buildbucket for the build status
[08:03:25.705] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:25.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:86:0) after 1m0s
[08:04:25.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:86:0)
[08:04:25.756] Timer tick, asking Buildbucket for the build status
[08:04:25.854] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:25.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:87:0) after 1m0s
[08:05:25.986] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:87:0)
[08:05:25.986] Timer tick, asking Buildbucket for the build status
[08:05:26.152] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:26.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:88:0) after 1m0s
[08:06:26.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:88:0)
[08:06:26.178] Timer tick, asking Buildbucket for the build status
[08:06:26.382] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:26.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:89:0) after 1m0s
[08:07:26.480] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:89:0)
[08:07:26.480] Timer tick, asking Buildbucket for the build status
[08:07:26.557] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:26.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:90:0) after 1m0s
[08:08:26.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:90:0)
[08:08:26.607] Timer tick, asking Buildbucket for the build status
[08:08:26.698] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:26.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:91:0) after 1m0s
[08:09:26.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:91:0)
[08:09:26.868] Timer tick, asking Buildbucket for the build status
[08:09:26.976] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:26.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:92:0) after 1m0s
[08:10:27.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:92:0)
[08:10:27.041] Timer tick, asking Buildbucket for the build status
[08:10:27.327] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:27.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:93:0) after 1m0s
[08:11:27.373] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:93:0)
[08:11:27.373] Timer tick, asking Buildbucket for the build status
[08:11:27.672] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:27.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:94:0) after 1m0s
[08:12:27.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:94:0)
[08:12:27.794] Timer tick, asking Buildbucket for the build status
[08:12:27.863] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:27.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:95:0) after 1m0s
[08:13:27.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:95:0)
[08:13:27.958] Timer tick, asking Buildbucket for the build status
[08:13:28.047] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:28.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:96:0) after 1m0s
[08:14:28.197] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:96:0)
[08:14:28.197] Timer tick, asking Buildbucket for the build status
[08:14:28.341] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:28.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:97:0) after 1m0s
[08:15:28.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:97:0)
[08:15:28.395] Timer tick, asking Buildbucket for the build status
[08:15:28.839] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:28.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:98:0) after 1m0s
[08:16:29.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:98:0)
[08:16:29.075] Timer tick, asking Buildbucket for the build status
[08:16:29.149] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:29.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:99:0) after 1m0s
[08:17:29.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:99:0)
[08:17:29.231] Timer tick, asking Buildbucket for the build status
[08:17:29.406] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:29.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:100:0) after 1m0s
[08:18:29.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:100:0)
[08:18:29.528] Timer tick, asking Buildbucket for the build status
[08:18:29.612] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:29.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:101:0) after 1m0s
[08:19:29.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:101:0)
[08:19:29.764] Timer tick, asking Buildbucket for the build status
[08:19:30.032] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:30.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:102:0) after 1m0s
[08:20:30.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:102:0)
[08:20:30.069] Timer tick, asking Buildbucket for the build status
[08:20:30.196] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:30.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:103:0) after 1m0s
[08:21:30.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:103:0)
[08:21:30.310] Timer tick, asking Buildbucket for the build status
[08:21:30.420] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:30.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:104:0) after 1m0s
[08:22:30.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:104:0)
[08:22:30.484] Timer tick, asking Buildbucket for the build status
[08:22:30.537] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:30.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:105:0) after 1m0s
[08:23:30.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:105:0)
[08:23:30.590] Timer tick, asking Buildbucket for the build status
[08:23:30.673] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:30.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:106:0) after 1m0s
[08:24:30.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:106:0)
[08:24:30.732] Timer tick, asking Buildbucket for the build status
[08:24:30.885] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:30.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:107:0) after 1m0s
[08:25:30.874] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:107:0)
[08:25:30.874] Timer tick, asking Buildbucket for the build status
[08:25:31.272] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:31.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:108:0) after 1m0s
[08:26:31.300] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:108:0)
[08:26:31.300] Timer tick, asking Buildbucket for the build status
[08:26:31.648] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:31.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:109:0) after 1m0s
[08:27:31.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:109:0)
[08:27:31.685] Timer tick, asking Buildbucket for the build status
[08:27:31.767] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:31.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:110:0) after 1m0s
[08:28:31.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:110:0)
[08:28:31.783] Timer tick, asking Buildbucket for the build status
[08:28:31.889] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:31.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:111:0) after 1m0s
[08:29:31.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:111:0)
[08:29:31.957] Timer tick, asking Buildbucket for the build status
[08:29:32.124] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:32.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:112:0) after 1m0s
[08:30:32.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:112:0)
[08:30:32.155] Timer tick, asking Buildbucket for the build status
[08:30:32.306] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:32.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:113:0) after 1m0s
[08:31:32.331] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:113:0)
[08:31:32.331] Timer tick, asking Buildbucket for the build status
[08:31:32.600] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:32.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:114:0) after 1m0s
[08:32:32.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:114:0)
[08:32:32.697] Timer tick, asking Buildbucket for the build status
[08:32:32.793] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:32.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:115:0) after 1m0s
[08:33:32.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:115:0)
[08:33:32.845] Timer tick, asking Buildbucket for the build status
[08:33:33.221] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:33.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:116:0) after 1m0s
[08:34:33.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:116:0)
[08:34:33.237] Timer tick, asking Buildbucket for the build status
[08:34:33.341] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:33.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:117:0) after 1m0s
[08:35:33.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:117:0)
[08:35:33.432] Timer tick, asking Buildbucket for the build status
[08:35:33.914] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:33.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:118:0) after 1m0s
[08:36:33.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:118:0)
[08:36:33.939] Timer tick, asking Buildbucket for the build status
[08:36:34.024] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:34.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:119:0) after 1m0s
[08:37:34.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:119:0)
[08:37:34.074] Timer tick, asking Buildbucket for the build status
[08:37:34.326] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:34.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:120:0) after 1m0s
[08:38:34.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:120:0)
[08:38:34.388] Timer tick, asking Buildbucket for the build status
[08:38:34.596] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:34.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:121:0) after 1m0s
[08:39:34.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:121:0)
[08:39:34.621] Timer tick, asking Buildbucket for the build status
[08:39:34.742] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:34.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:122:0) after 1m0s
[08:40:34.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:122:0)
[08:40:34.788] Timer tick, asking Buildbucket for the build status
[08:40:35.016] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:35.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:123:0) after 1m0s
[08:41:35.054] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:123:0)
[08:41:35.054] Timer tick, asking Buildbucket for the build status
[08:41:35.128] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:35.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:124:0) after 1m0s
[08:42:35.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:124:0)
[08:42:35.159] Timer tick, asking Buildbucket for the build status
[08:42:35.543] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:35.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:125:0) after 1m0s
[08:43:35.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:125:0)
[08:43:35.636] Timer tick, asking Buildbucket for the build status
[08:43:35.793] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:35.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:126:0) after 1m0s
[08:44:36.061] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:126:0)
[08:44:36.061] Timer tick, asking Buildbucket for the build status
[08:44:36.132] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:36.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:127:0) after 1m0s
[08:45:36.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:127:0)
[08:45:36.152] Timer tick, asking Buildbucket for the build status
[08:45:36.342] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:36.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:128:0) after 1m0s
[08:46:36.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:128:0)
[08:46:36.414] Timer tick, asking Buildbucket for the build status
[08:46:36.637] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:36.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:129:0) after 1m0s
[08:47:37.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:129:0)
[08:47:37.544] Timer tick, asking Buildbucket for the build status
[08:47:38.243] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:38.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:130:0) after 1m0s
[08:48:38.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:130:0)
[08:48:38.383] Timer tick, asking Buildbucket for the build status
[08:48:38.969] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:38.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:131:0) after 1m0s
[08:49:39.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:131:0)
[08:49:39.147] Timer tick, asking Buildbucket for the build status
[08:49:39.256] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:39.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:132:0) after 1m0s
[08:50:39.284] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:132:0)
[08:50:39.284] Timer tick, asking Buildbucket for the build status
[08:50:39.386] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:39.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:133:0) after 1m0s
[08:51:39.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:133:0)
[08:51:39.539] Timer tick, asking Buildbucket for the build status
[08:51:39.624] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:39.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:134:0) after 1m0s
[08:52:39.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:134:0)
[08:52:39.744] Timer tick, asking Buildbucket for the build status
[08:52:39.916] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:39.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:135:0) after 1m0s
[08:53:40.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:135:0)
[08:53:40.141] Timer tick, asking Buildbucket for the build status
[08:53:40.239] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:40.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:136:0) after 1m0s
[08:54:40.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:136:0)
[08:54:40.413] Timer tick, asking Buildbucket for the build status
[08:54:40.512] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:40.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:137:0) after 1m0s
[08:55:40.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:137:0)
[08:55:40.629] Timer tick, asking Buildbucket for the build status
[08:55:40.736] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:40.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:138:0) after 1m0s
[08:56:40.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:138:0)
[08:56:40.993] Timer tick, asking Buildbucket for the build status
[08:56:41.991] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:41.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:139:0) after 1m0s
[08:57:42.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:139:0)
[08:57:42.178] Timer tick, asking Buildbucket for the build status
[08:57:42.266] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:42.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:140:0) after 1m0s
[08:58:42.494] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:140:0)
[08:58:42.494] Timer tick, asking Buildbucket for the build status
[08:58:42.697] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:42.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:141:0) after 1m0s
[08:59:42.865] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:141:0)
[08:59:42.865] Timer tick, asking Buildbucket for the build status
[08:59:42.933] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:42.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:142:0) after 1m0s
[09:00:43.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:142:0)
[09:00:43.448] Timer tick, asking Buildbucket for the build status
[09:00:43.617] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:43.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:143:0) after 1m0s
[09:01:43.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:143:0)
[09:01:43.829] Timer tick, asking Buildbucket for the build status
[09:01:43.894] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:43.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:144:0) after 1m0s
[09:02:44.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:144:0)
[09:02:44.037] Timer tick, asking Buildbucket for the build status
[09:02:44.242] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:44.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:145:0) after 1m0s
[09:03:44.424] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:145:0)
[09:03:44.424] Timer tick, asking Buildbucket for the build status
[09:03:44.554] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:44.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:146:0) after 1m0s
[09:04:44.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:146:0)
[09:04:44.636] Timer tick, asking Buildbucket for the build status
[09:04:44.792] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:44.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:147:0) after 1m0s
[09:05:44.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:147:0)
[09:05:44.899] Timer tick, asking Buildbucket for the build status
[09:05:45.050] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:45.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:148:0) after 1m0s
[09:06:45.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:148:0)
[09:06:45.163] Timer tick, asking Buildbucket for the build status
[09:06:45.309] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:45.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:149:0) after 1m0s
[09:07:45.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:149:0)
[09:07:45.347] Timer tick, asking Buildbucket for the build status
[09:07:45.433] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:45.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:150:0) after 1m0s
[09:08:45.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:150:0)
[09:08:45.599] Timer tick, asking Buildbucket for the build status
[09:08:45.714] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:45.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:151:0) after 1m0s
[09:09:45.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:151:0)
[09:09:45.969] Timer tick, asking Buildbucket for the build status
[09:09:46.190] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:46.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:152:0) after 1m0s
[09:10:46.472] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:152:0)
[09:10:46.472] Timer tick, asking Buildbucket for the build status
[09:10:46.825] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:46.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:153:0) after 1m0s
[09:11:46.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:153:0)
[09:11:46.938] Timer tick, asking Buildbucket for the build status
[09:11:47.003] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:47.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:154:0) after 1m0s
[09:12:47.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:154:0)
[09:12:47.109] Timer tick, asking Buildbucket for the build status
[09:12:47.250] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:47.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:155:0) after 1m0s
[09:13:47.284] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:155:0)
[09:13:47.284] Timer tick, asking Buildbucket for the build status
[09:13:47.349] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:47.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:156:0) after 1m0s
[09:14:47.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:156:0)
[09:14:47.442] Timer tick, asking Buildbucket for the build status
[09:14:47.522] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:47.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:157:0) after 1m0s
[09:15:47.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:157:0)
[09:15:47.573] Timer tick, asking Buildbucket for the build status
[09:15:47.900] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:47.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:158:0) after 1m0s
[09:16:47.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:158:0)
[09:16:47.942] Timer tick, asking Buildbucket for the build status
[09:16:48.878] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:48.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:159:0) after 1m0s
[09:17:48.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:159:0)
[09:17:48.951] Timer tick, asking Buildbucket for the build status
[09:17:49.288] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:49.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:160:0) after 1m0s
[09:18:49.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:160:0)
[09:18:49.411] Timer tick, asking Buildbucket for the build status
[09:18:49.486] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:49.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:161:0) after 1m0s
[09:19:49.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:161:0)
[09:19:49.521] Timer tick, asking Buildbucket for the build status
[09:19:49.590] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:49.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:162:0) after 1m0s
[09:20:49.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:162:0)
[09:20:49.621] Timer tick, asking Buildbucket for the build status
[09:20:49.741] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:49.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:163:0) after 1m0s
[09:21:49.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:163:0)
[09:21:49.802] Timer tick, asking Buildbucket for the build status
[09:21:49.869] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:49.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:164:0) after 1m0s
[09:22:49.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:164:0)
[09:22:49.978] Timer tick, asking Buildbucket for the build status
[09:22:50.196] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:50.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:165:0) after 1m0s
[09:23:50.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:165:0)
[09:23:50.233] Timer tick, asking Buildbucket for the build status
[09:23:50.545] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:50.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:166:0) after 1m0s
[09:24:50.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:166:0)
[09:24:50.591] Timer tick, asking Buildbucket for the build status
[09:24:50.805] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:50.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:167:0) after 1m0s
[09:25:50.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:167:0)
[09:25:50.863] Timer tick, asking Buildbucket for the build status
[09:25:51.084] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:51.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:168:0) after 1m0s
[09:26:51.115] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:168:0)
[09:26:51.115] Timer tick, asking Buildbucket for the build status
[09:26:51.265] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:51.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:169:0) after 1m0s
[09:27:51.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:169:0)
[09:27:51.310] Timer tick, asking Buildbucket for the build status
[09:27:51.411] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:51.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:170:0) after 1m0s
[09:28:51.435] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:170:0)
[09:28:51.435] Timer tick, asking Buildbucket for the build status
[09:28:51.513] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:51.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:171:0) after 1m0s
[09:29:51.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:171:0)
[09:29:51.542] Timer tick, asking Buildbucket for the build status
[09:29:51.713] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:51.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:172:0) after 1m0s
[09:30:51.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:172:0)
[09:30:51.798] Timer tick, asking Buildbucket for the build status
[09:30:52.271] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:52.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:173:0) after 1m0s
[09:31:52.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:173:0)
[09:31:52.306] Timer tick, asking Buildbucket for the build status
[09:31:52.378] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:52.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:174:0) after 1m0s
[09:32:52.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:174:0)
[09:32:52.389] Timer tick, asking Buildbucket for the build status
[09:32:52.456] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:52.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:175:0) after 1m0s
[09:33:52.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:175:0)
[09:33:52.571] Timer tick, asking Buildbucket for the build status
[09:33:52.711] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:52.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:176:0) after 1m0s
[09:34:52.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:176:0)
[09:34:52.734] Timer tick, asking Buildbucket for the build status
[09:34:52.954] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:52.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:177:0) after 1m0s
[09:35:52.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:177:0)
[09:35:52.999] Timer tick, asking Buildbucket for the build status
[09:35:53.168] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:53.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:178:0) after 1m0s
[09:36:53.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:178:0)
[09:36:53.228] Timer tick, asking Buildbucket for the build status
[09:36:53.308] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:53.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:179:0) after 1m0s
[09:37:53.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:179:0)
[09:37:53.358] Timer tick, asking Buildbucket for the build status
[09:37:53.420] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:53.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:180:0) after 1m0s
[09:38:53.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:180:0)
[09:38:53.454] Timer tick, asking Buildbucket for the build status
[09:38:53.943] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:53.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:181:0) after 1m0s
[09:39:54.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:181:0)
[09:39:54.041] Timer tick, asking Buildbucket for the build status
[09:39:54.122] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:54.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:182:0) after 1m0s
[09:40:54.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:182:0)
[09:40:54.204] Timer tick, asking Buildbucket for the build status
[09:40:54.497] Build 8870743480002909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:54.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036173542022453904:183:0) after 1m0s
[09:41:00.280] Received PubSub notification, asking Buildbucket for the build status
[09:41:00.344] Build 8870743480002909200: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:41:00.344] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1598607659457843",
"created_by": "project:chromium",
"created_ts": "1598596797901303",
"id": "8870743480002909200",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c0d4a164f0dd1fe9ebb45cfabfec8357522e345\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$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-6-86zm\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 441, \"got_angle_revision\": \"a1d05e35a865d588a513a7ea6b6668f86919b7d7\", \"got_dawn_revision\": \"900bd341a3c6fc71dfbaeac8adb45b1da0de53d0\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"0c0d4a164f0dd1fe9ebb45cfabfec8357522e345\", \"got_revision_cp\": \"refs/heads/master@{#802546}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"ec3650f56ca2cc032a46ee4eeb86cccd2b78a6b5\", \"got_v8_revision\": \"1f2195b49c6d7611165ebd149b2825f27693930c\", \"got_v8_revision_cp\": \"refs/heads/8.7.25@{#1}\", \"got_webrtc_revision\": \"6b381c7456a995e4d866c30d308e5fd7a7670286\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32010}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c0d4a164f0dd1fe9ebb45cfabfec8357522e345\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-6-86zm\"], \"image\": [\"chrome-win10-1703-20081700-d9392568d25\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5498-3454aa6\"], \"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": "1598596839841079",
"status": "COMPLETED",
"status_changed_ts": "1598607660004178",
"tags": [
"build_address:luci.chromium.ci/win-official/441",
"builder:win-official",
"buildset:commit/git/0c0d4a164f0dd1fe9ebb45cfabfec8357522e345",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c0d4a164f0dd1fe9ebb45cfabfec8357522e345",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036173542022453904",
"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/8870743480002909200/+/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:4e4c9cf74d0df610",
"user_agent:luci-scheduler"
],
"updated_ts": "1598607660013879",
"url": "https://ci.chromium.org/b/8870743480002909200",
"utcnow_ts": "1598607660337288"
}
}
[09:41:00.344] Invocation finished in 3h1m3.753919625s with status SUCCEEDED