[06:52:58.693] New invocation is queued and will start shortly
[06:52:59.855] Starting the invocation (attempt 1)
[06:52:59.892] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:52:59.892] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:52:59.892] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021858401254915520",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"127c5d89d0691fb959bd409cf1f3ad094af5f4f6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9021858401254915520",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/127c5d89d0691fb959bd409cf1f3ad094af5f4f6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/127c5d89d0691fb959bd409cf1f3ad094af5f4f6",
"gitiles_ref:refs/heads/master"
]
}
[06:53:00.443] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612248780005234",
"id": "8856428339215205440",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"127c5d89d0691fb959bd409cf1f3ad094af5f4f6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612248780334809",
"tags": [
"build_address:luci.chromium.ci/win-official/1517",
"builder:win-official",
"buildset:commit/git/127c5d89d0691fb959bd409cf1f3ad094af5f4f6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/127c5d89d0691fb959bd409cf1f3ad094af5f4f6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021858401254915520",
"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/8856428339215205440/+/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": "1612248780334918",
"url": "https://ci.chromium.org/b/8856428339215205440",
"utcnow_ts": "1612248780437446"
}
}
[06:53:00.444] Task URL: https://ci.chromium.org/b/8856428339215205440
[06:53:00.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:2:0) after 1m0s
[06:53:47.633] Received PubSub notification, asking Buildbucket for the build status
[06:53:47.706] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:00.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:2:0)
[06:54:00.465] Timer tick, asking Buildbucket for the build status
[06:54:00.811] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:00.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:4:0) after 1m0s
[06:55:00.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:4:0)
[06:55:00.836] Timer tick, asking Buildbucket for the build status
[06:55:00.927] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:00.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:5:0) after 1m0s
[06:56:01.290] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:5:0)
[06:56:01.290] Timer tick, asking Buildbucket for the build status
[06:56:01.609] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:01.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:6:0) after 1m0s
[06:57:01.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:6:0)
[06:57:01.674] Timer tick, asking Buildbucket for the build status
[06:57:01.743] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:01.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:7:0) after 1m0s
[06:58:01.766] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:7:0)
[06:58:01.766] Timer tick, asking Buildbucket for the build status
[06:58:01.873] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:01.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:8:0) after 1m0s
[06:59:01.950] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:8:0)
[06:59:01.950] Timer tick, asking Buildbucket for the build status
[06:59:02.082] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:02.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:9:0) after 1m0s
[07:00:02.108] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:9:0)
[07:00:02.108] Timer tick, asking Buildbucket for the build status
[07:00:02.177] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:02.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:10:0) after 1m0s
[07:01:02.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:10:0)
[07:01:02.193] Timer tick, asking Buildbucket for the build status
[07:01:02.295] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:02.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:11:0) after 1m0s
[07:02:02.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:11:0)
[07:02:02.327] Timer tick, asking Buildbucket for the build status
[07:02:02.431] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:02.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:12:0) after 1m0s
[07:03:02.559] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:12:0)
[07:03:02.559] Timer tick, asking Buildbucket for the build status
[07:03:02.659] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:02.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:13:0) after 1m0s
[07:04:02.751] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:13:0)
[07:04:02.751] Timer tick, asking Buildbucket for the build status
[07:04:03.501] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:03.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:14:0) after 1m0s
[07:05:03.540] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:14:0)
[07:05:03.540] Timer tick, asking Buildbucket for the build status
[07:05:03.651] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:03.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:15:0) after 1m0s
[07:06:03.693] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:15:0)
[07:06:03.693] Timer tick, asking Buildbucket for the build status
[07:06:03.870] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:03.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:16:0) after 1m0s
[07:07:03.902] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:16:0)
[07:07:03.902] Timer tick, asking Buildbucket for the build status
[07:07:04.025] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:04.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:17:0) after 1m0s
[07:08:04.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:17:0)
[07:08:04.029] Timer tick, asking Buildbucket for the build status
[07:08:04.092] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:04.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:18:0) after 1m0s
[07:09:04.087] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:18:0)
[07:09:04.087] Timer tick, asking Buildbucket for the build status
[07:09:04.405] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:04.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:19:0) after 1m0s
[07:10:04.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:19:0)
[07:10:04.482] Timer tick, asking Buildbucket for the build status
[07:10:04.546] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:04.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:20:0) after 1m0s
[07:11:04.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:20:0)
[07:11:04.729] Timer tick, asking Buildbucket for the build status
[07:11:04.946] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:04.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:21:0) after 1m0s
[07:12:05.280] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:21:0)
[07:12:05.280] Timer tick, asking Buildbucket for the build status
[07:12:05.365] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:05.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:22:0) after 1m0s
[07:13:05.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:22:0)
[07:13:05.469] Timer tick, asking Buildbucket for the build status
[07:13:05.556] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:05.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:23:0) after 1m0s
[07:14:05.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:23:0)
[07:14:05.809] Timer tick, asking Buildbucket for the build status
[07:14:06.484] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:06.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:24:0) after 1m0s
[07:15:06.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:24:0)
[07:15:06.595] Timer tick, asking Buildbucket for the build status
[07:15:06.728] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:06.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:25:0) after 1m0s
[07:16:06.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:25:0)
[07:16:06.783] Timer tick, asking Buildbucket for the build status
[07:16:06.996] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:06.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:26:0) after 1m0s
[07:17:07.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:26:0)
[07:17:07.079] Timer tick, asking Buildbucket for the build status
[07:17:07.189] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:07.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:27:0) after 1m0s
[07:18:07.311] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:27:0)
[07:18:07.311] Timer tick, asking Buildbucket for the build status
[07:18:07.411] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:07.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:28:0) after 1m0s
[07:19:07.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:28:0)
[07:19:07.498] Timer tick, asking Buildbucket for the build status
[07:19:07.845] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:07.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:29:0) after 1m0s
[07:20:07.882] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:29:0)
[07:20:07.882] Timer tick, asking Buildbucket for the build status
[07:20:07.968] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:07.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:30:0) after 1m0s
[07:21:08.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:30:0)
[07:21:08.094] Timer tick, asking Buildbucket for the build status
[07:21:08.187] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:08.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:31:0) after 1m0s
[07:22:08.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:31:0)
[07:22:08.177] Timer tick, asking Buildbucket for the build status
[07:22:08.234] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:08.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:32:0) after 1m0s
[07:23:08.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:32:0)
[07:23:08.316] Timer tick, asking Buildbucket for the build status
[07:23:08.380] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:08.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:33:0) after 1m0s
[07:24:08.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:33:0)
[07:24:08.455] Timer tick, asking Buildbucket for the build status
[07:24:08.578] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:08.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:34:0) after 1m0s
[07:25:08.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:34:0)
[07:25:08.763] Timer tick, asking Buildbucket for the build status
[07:25:08.838] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:08.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:35:0) after 1m0s
[07:26:08.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:35:0)
[07:26:08.892] Timer tick, asking Buildbucket for the build status
[07:26:08.959] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:08.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:36:0) after 1m0s
[07:27:09.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:36:0)
[07:27:09.141] Timer tick, asking Buildbucket for the build status
[07:27:09.233] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:09.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:37:0) after 1m0s
[07:28:09.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:37:0)
[07:28:09.339] Timer tick, asking Buildbucket for the build status
[07:28:09.405] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:09.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:38:0) after 1m0s
[07:29:09.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:38:0)
[07:29:09.465] Timer tick, asking Buildbucket for the build status
[07:29:09.576] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:09.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:39:0) after 1m0s
[07:30:09.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:39:0)
[07:30:09.707] Timer tick, asking Buildbucket for the build status
[07:30:09.837] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:09.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:40:0) after 1m0s
[07:31:09.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:40:0)
[07:31:09.924] Timer tick, asking Buildbucket for the build status
[07:31:10.102] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:10.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:41:0) after 1m0s
[07:32:10.133] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:41:0)
[07:32:10.133] Timer tick, asking Buildbucket for the build status
[07:32:10.234] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:10.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:42:0) after 1m0s
[07:33:10.248] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:42:0)
[07:33:10.248] Timer tick, asking Buildbucket for the build status
[07:33:10.974] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:10.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:43:0) after 1m0s
[07:34:11.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:43:0)
[07:34:11.021] Timer tick, asking Buildbucket for the build status
[07:34:11.092] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:11.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:44:0) after 1m0s
[07:35:11.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:44:0)
[07:35:11.123] Timer tick, asking Buildbucket for the build status
[07:35:11.198] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:11.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:45:0) after 1m0s
[07:36:11.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:45:0)
[07:36:11.214] Timer tick, asking Buildbucket for the build status
[07:36:11.367] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:11.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:46:0) after 1m0s
[07:37:11.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:46:0)
[07:37:11.402] Timer tick, asking Buildbucket for the build status
[07:37:11.470] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:11.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:47:0) after 1m0s
[07:38:11.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:47:0)
[07:38:11.687] Timer tick, asking Buildbucket for the build status
[07:38:12.033] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:12.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:48:0) after 1m0s
[07:39:12.257] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:48:0)
[07:39:12.257] Timer tick, asking Buildbucket for the build status
[07:39:12.335] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:12.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:49:0) after 1m0s
[07:40:12.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:49:0)
[07:40:12.613] Timer tick, asking Buildbucket for the build status
[07:40:12.703] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:12.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:50:0) after 1m0s
[07:41:12.928] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:50:0)
[07:41:12.928] Timer tick, asking Buildbucket for the build status
[07:41:13.046] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:13.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:51:0) after 1m0s
[07:42:13.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:51:0)
[07:42:13.182] Timer tick, asking Buildbucket for the build status
[07:42:13.406] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:13.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:52:0) after 1m0s
[07:43:13.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:52:0)
[07:43:13.620] Timer tick, asking Buildbucket for the build status
[07:43:13.791] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:13.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:53:0) after 1m0s
[07:44:13.878] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:53:0)
[07:44:13.878] Timer tick, asking Buildbucket for the build status
[07:44:13.972] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:13.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:54:0) after 1m0s
[07:45:14.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:54:0)
[07:45:14.768] Timer tick, asking Buildbucket for the build status
[07:45:14.835] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:14.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:55:0) after 1m0s
[07:46:15.018] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:55:0)
[07:46:15.018] Timer tick, asking Buildbucket for the build status
[07:46:15.072] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:15.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:56:0) after 1m0s
[07:47:15.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:56:0)
[07:47:15.466] Timer tick, asking Buildbucket for the build status
[07:47:15.629] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:15.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:57:0) after 1m0s
[07:48:15.733] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:57:0)
[07:48:15.733] Timer tick, asking Buildbucket for the build status
[07:48:16.263] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:16.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:58:0) after 1m0s
[07:49:16.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:58:0)
[07:49:16.409] Timer tick, asking Buildbucket for the build status
[07:49:16.603] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:16.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:59:0) after 1m0s
[07:50:16.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:59:0)
[07:50:16.645] Timer tick, asking Buildbucket for the build status
[07:50:16.757] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:16.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:60:0) after 1m0s
[07:51:16.790] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:60:0)
[07:51:16.790] Timer tick, asking Buildbucket for the build status
[07:51:16.852] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:16.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:61:0) after 1m0s
[07:52:16.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:61:0)
[07:52:16.905] Timer tick, asking Buildbucket for the build status
[07:52:17.035] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:17.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:62:0) after 1m0s
[07:53:17.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:62:0)
[07:53:17.111] Timer tick, asking Buildbucket for the build status
[07:53:17.295] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:17.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:63:0) after 1m0s
[07:54:17.324] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:63:0)
[07:54:17.324] Timer tick, asking Buildbucket for the build status
[07:54:17.465] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:17.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:64:0) after 1m0s
[07:55:17.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:64:0)
[07:55:17.477] Timer tick, asking Buildbucket for the build status
[07:55:17.538] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:17.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:65:0) after 1m0s
[07:56:17.706] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:65:0)
[07:56:17.706] Timer tick, asking Buildbucket for the build status
[07:56:18.167] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:18.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:66:0) after 1m0s
[07:57:18.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:66:0)
[07:57:18.303] Timer tick, asking Buildbucket for the build status
[07:57:18.443] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:18.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:67:0) after 1m0s
[07:58:18.609] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:67:0)
[07:58:18.609] Timer tick, asking Buildbucket for the build status
[07:58:20.249] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:20.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:68:0) after 1m0s
[07:59:20.433] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:68:0)
[07:59:20.433] Timer tick, asking Buildbucket for the build status
[07:59:20.552] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:20.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:69:0) after 1m0s
[08:00:20.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:69:0)
[08:00:20.798] Timer tick, asking Buildbucket for the build status
[08:00:20.885] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:20.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:70:0) after 1m0s
[08:01:20.961] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:70:0)
[08:01:20.961] Timer tick, asking Buildbucket for the build status
[08:01:21.210] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:21.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:71:0) after 1m0s
[08:02:21.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:71:0)
[08:02:21.437] Timer tick, asking Buildbucket for the build status
[08:02:21.563] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:21.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:72:0) after 1m0s
[08:03:21.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:72:0)
[08:03:21.647] Timer tick, asking Buildbucket for the build status
[08:03:21.747] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:21.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:73:0) after 1m0s
[08:04:21.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:73:0)
[08:04:21.832] Timer tick, asking Buildbucket for the build status
[08:04:21.935] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:21.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:74:0) after 1m0s
[08:05:21.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:74:0)
[08:05:21.983] Timer tick, asking Buildbucket for the build status
[08:05:22.058] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:22.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:75:0) after 1m0s
[08:06:22.084] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:75:0)
[08:06:22.084] Timer tick, asking Buildbucket for the build status
[08:06:22.162] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:22.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:76:0) after 1m0s
[08:07:22.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:76:0)
[08:07:22.173] Timer tick, asking Buildbucket for the build status
[08:07:22.269] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:22.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:77:0) after 1m0s
[08:08:22.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:77:0)
[08:08:22.369] Timer tick, asking Buildbucket for the build status
[08:08:22.521] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:22.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:78:0) after 1m0s
[08:09:22.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:78:0)
[08:09:22.595] Timer tick, asking Buildbucket for the build status
[08:09:22.683] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:22.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:79:0) after 1m0s
[08:10:22.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:79:0)
[08:10:22.717] Timer tick, asking Buildbucket for the build status
[08:10:22.775] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:22.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:80:0) after 1m0s
[08:11:22.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:80:0)
[08:11:22.832] Timer tick, asking Buildbucket for the build status
[08:11:22.907] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:22.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:81:0) after 1m0s
[08:12:22.947] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:81:0)
[08:12:22.947] Timer tick, asking Buildbucket for the build status
[08:12:23.093] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:23.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:82:0) after 1m0s
[08:13:23.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:82:0)
[08:13:23.264] Timer tick, asking Buildbucket for the build status
[08:13:23.609] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:23.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:83:0) after 1m0s
[08:14:23.661] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:83:0)
[08:14:23.661] Timer tick, asking Buildbucket for the build status
[08:14:23.786] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:23.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:84:0) after 1m0s
[08:15:23.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:84:0)
[08:15:23.809] Timer tick, asking Buildbucket for the build status
[08:15:23.924] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:23.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:85:0) after 1m0s
[08:16:23.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:85:0)
[08:16:23.918] Timer tick, asking Buildbucket for the build status
[08:16:24.048] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:24.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:86:0) after 1m0s
[08:17:24.031] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:86:0)
[08:17:24.031] Timer tick, asking Buildbucket for the build status
[08:17:24.133] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:24.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:87:0) after 1m0s
[08:18:24.213] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:87:0)
[08:18:24.213] Timer tick, asking Buildbucket for the build status
[08:18:24.378] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:24.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:88:0) after 1m0s
[08:19:24.373] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:88:0)
[08:19:24.373] Timer tick, asking Buildbucket for the build status
[08:19:24.438] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:24.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:89:0) after 1m0s
[08:20:24.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:89:0)
[08:20:24.475] Timer tick, asking Buildbucket for the build status
[08:20:24.559] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:24.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:90:0) after 1m0s
[08:21:24.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:90:0)
[08:21:24.708] Timer tick, asking Buildbucket for the build status
[08:21:24.780] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:24.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:91:0) after 1m0s
[08:22:24.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:91:0)
[08:22:24.905] Timer tick, asking Buildbucket for the build status
[08:22:24.966] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:24.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:92:0) after 1m0s
[08:23:25.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:92:0)
[08:23:25.117] Timer tick, asking Buildbucket for the build status
[08:23:25.275] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:25.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:93:0) after 1m0s
[08:24:25.564] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:93:0)
[08:24:25.564] Timer tick, asking Buildbucket for the build status
[08:24:25.650] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:25.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:94:0) after 1m0s
[08:25:25.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:94:0)
[08:25:25.727] Timer tick, asking Buildbucket for the build status
[08:25:25.825] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:25.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:95:0) after 1m0s
[08:26:25.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:95:0)
[08:26:25.949] Timer tick, asking Buildbucket for the build status
[08:26:26.098] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:26.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:96:0) after 1m0s
[08:27:26.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:96:0)
[08:27:26.159] Timer tick, asking Buildbucket for the build status
[08:27:26.226] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:26.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:97:0) after 1m0s
[08:28:26.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:97:0)
[08:28:26.293] Timer tick, asking Buildbucket for the build status
[08:28:26.823] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:26.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:98:0) after 1m0s
[08:29:26.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:98:0)
[08:29:26.909] Timer tick, asking Buildbucket for the build status
[08:29:26.982] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:26.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:99:0) after 1m0s
[08:30:27.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:99:0)
[08:30:27.083] Timer tick, asking Buildbucket for the build status
[08:30:27.186] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:27.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:100:0) after 1m0s
[08:31:27.319] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:100:0)
[08:31:27.319] Timer tick, asking Buildbucket for the build status
[08:31:27.385] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:27.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:101:0) after 1m0s
[08:32:27.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:101:0)
[08:32:27.416] Timer tick, asking Buildbucket for the build status
[08:32:27.488] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:27.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:102:0) after 1m0s
[08:33:27.577] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:102:0)
[08:33:27.577] Timer tick, asking Buildbucket for the build status
[08:33:27.910] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:27.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:103:0) after 1m0s
[08:34:27.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:103:0)
[08:34:27.987] Timer tick, asking Buildbucket for the build status
[08:34:28.090] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:28.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:104:0) after 1m0s
[08:35:28.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:104:0)
[08:35:28.126] Timer tick, asking Buildbucket for the build status
[08:35:28.195] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:28.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:105:0) after 1m0s
[08:36:28.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:105:0)
[08:36:28.219] Timer tick, asking Buildbucket for the build status
[08:36:28.269] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:28.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:106:0) after 1m0s
[08:37:28.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:106:0)
[08:37:28.312] Timer tick, asking Buildbucket for the build status
[08:37:28.384] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:28.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:107:0) after 1m0s
[08:38:28.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:107:0)
[08:38:28.395] Timer tick, asking Buildbucket for the build status
[08:38:28.491] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:28.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:108:0) after 1m0s
[08:39:28.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:108:0)
[08:39:28.574] Timer tick, asking Buildbucket for the build status
[08:39:28.648] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:28.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:109:0) after 1m0s
[08:40:28.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:109:0)
[08:40:28.747] Timer tick, asking Buildbucket for the build status
[08:40:29.141] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:29.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:110:0) after 1m0s
[08:41:29.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:110:0)
[08:41:29.328] Timer tick, asking Buildbucket for the build status
[08:41:29.520] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:29.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:111:0) after 1m0s
[08:42:29.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:111:0)
[08:42:29.741] Timer tick, asking Buildbucket for the build status
[08:42:29.812] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:29.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:112:0) after 1m0s
[08:43:29.998] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:112:0)
[08:43:29.998] Timer tick, asking Buildbucket for the build status
[08:43:30.132] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:30.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:113:0) after 1m0s
[08:44:30.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:113:0)
[08:44:30.292] Timer tick, asking Buildbucket for the build status
[08:44:30.450] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:30.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:114:0) after 1m0s
[08:45:30.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:114:0)
[08:45:30.515] Timer tick, asking Buildbucket for the build status
[08:45:30.778] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:30.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:115:0) after 1m0s
[08:46:30.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:115:0)
[08:46:30.851] Timer tick, asking Buildbucket for the build status
[08:46:30.918] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:30.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:116:0) after 1m0s
[08:47:31.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:116:0)
[08:47:31.000] Timer tick, asking Buildbucket for the build status
[08:47:31.229] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:31.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:117:0) after 1m0s
[08:48:31.382] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:117:0)
[08:48:31.382] Timer tick, asking Buildbucket for the build status
[08:48:32.035] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:32.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:118:0) after 1m0s
[08:49:32.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:118:0)
[08:49:32.238] Timer tick, asking Buildbucket for the build status
[08:49:32.582] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:32.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:119:0) after 1m0s
[08:50:32.691] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:119:0)
[08:50:32.691] Timer tick, asking Buildbucket for the build status
[08:50:32.753] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:32.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:120:0) after 1m0s
[08:51:32.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:120:0)
[08:51:32.742] Timer tick, asking Buildbucket for the build status
[08:51:32.789] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:32.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:121:0) after 1m0s
[08:52:32.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:121:0)
[08:52:32.868] Timer tick, asking Buildbucket for the build status
[08:52:32.945] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:32.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:122:0) after 1m0s
[08:53:33.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:122:0)
[08:53:33.000] Timer tick, asking Buildbucket for the build status
[08:53:33.167] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:33.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:123:0) after 1m0s
[08:54:33.308] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:123:0)
[08:54:33.308] Timer tick, asking Buildbucket for the build status
[08:54:33.394] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:33.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:124:0) after 1m0s
[08:55:33.493] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:124:0)
[08:55:33.493] Timer tick, asking Buildbucket for the build status
[08:55:33.903] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:33.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:125:0) after 1m0s
[08:56:33.897] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:125:0)
[08:56:33.897] Timer tick, asking Buildbucket for the build status
[08:56:33.968] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:33.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:126:0) after 1m0s
[08:57:34.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:126:0)
[08:57:34.065] Timer tick, asking Buildbucket for the build status
[08:57:34.135] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:34.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:127:0) after 1m0s
[08:58:34.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:127:0)
[08:58:34.256] Timer tick, asking Buildbucket for the build status
[08:58:34.520] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:34.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:128:0) after 1m0s
[08:59:34.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:128:0)
[08:59:34.574] Timer tick, asking Buildbucket for the build status
[08:59:34.638] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:34.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:129:0) after 1m0s
[09:00:34.684] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:129:0)
[09:00:34.684] Timer tick, asking Buildbucket for the build status
[09:00:34.908] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:34.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:130:0) after 1m0s
[09:01:34.992] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:130:0)
[09:01:34.992] Timer tick, asking Buildbucket for the build status
[09:01:35.069] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:35.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:131:0) after 1m0s
[09:02:35.162] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:131:0)
[09:02:35.162] Timer tick, asking Buildbucket for the build status
[09:02:35.239] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:35.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:132:0) after 1m0s
[09:03:35.284] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:132:0)
[09:03:35.284] Timer tick, asking Buildbucket for the build status
[09:03:35.410] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:35.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:133:0) after 1m0s
[09:04:35.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:133:0)
[09:04:35.451] Timer tick, asking Buildbucket for the build status
[09:04:35.538] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:35.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:134:0) after 1m0s
[09:05:35.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:134:0)
[09:05:35.621] Timer tick, asking Buildbucket for the build status
[09:05:35.690] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:35.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:135:0) after 1m0s
[09:06:35.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:135:0)
[09:06:35.764] Timer tick, asking Buildbucket for the build status
[09:06:35.826] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:35.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:136:0) after 1m0s
[09:07:35.966] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:136:0)
[09:07:35.966] Timer tick, asking Buildbucket for the build status
[09:07:36.044] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:36.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:137:0) after 1m0s
[09:08:36.110] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:137:0)
[09:08:36.110] Timer tick, asking Buildbucket for the build status
[09:08:36.523] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:36.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:138:0) after 1m0s
[09:09:36.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:138:0)
[09:09:36.562] Timer tick, asking Buildbucket for the build status
[09:09:36.632] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:36.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:139:0) after 1m0s
[09:10:36.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:139:0)
[09:10:36.768] Timer tick, asking Buildbucket for the build status
[09:10:36.856] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:36.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:140:0) after 1m0s
[09:11:36.931] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:140:0)
[09:11:36.931] Timer tick, asking Buildbucket for the build status
[09:11:36.992] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:36.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:141:0) after 1m0s
[09:12:37.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:141:0)
[09:12:37.052] Timer tick, asking Buildbucket for the build status
[09:12:37.126] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:37.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:142:0) after 1m0s
[09:13:37.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:142:0)
[09:13:37.152] Timer tick, asking Buildbucket for the build status
[09:13:37.247] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:37.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:143:0) after 1m0s
[09:14:37.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:143:0)
[09:14:37.298] Timer tick, asking Buildbucket for the build status
[09:14:37.550] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:37.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:144:0) after 1m0s
[09:15:37.543] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:144:0)
[09:15:37.543] Timer tick, asking Buildbucket for the build status
[09:15:37.700] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:37.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:145:0) after 1m0s
[09:16:37.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:145:0)
[09:16:37.746] Timer tick, asking Buildbucket for the build status
[09:16:37.810] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:37.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:146:0) after 1m0s
[09:17:37.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:146:0)
[09:17:37.887] Timer tick, asking Buildbucket for the build status
[09:17:38.038] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:38.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:147:0) after 1m0s
[09:18:38.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:147:0)
[09:18:38.029] Timer tick, asking Buildbucket for the build status
[09:18:38.202] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:38.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:148:0) after 1m0s
[09:19:38.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:148:0)
[09:19:38.241] Timer tick, asking Buildbucket for the build status
[09:19:38.316] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:38.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:149:0) after 1m0s
[09:20:38.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:149:0)
[09:20:38.314] Timer tick, asking Buildbucket for the build status
[09:20:38.374] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:38.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:150:0) after 1m0s
[09:21:39.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:150:0)
[09:21:39.253] Timer tick, asking Buildbucket for the build status
[09:21:39.326] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:39.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:151:0) after 1m0s
[09:22:39.506] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:151:0)
[09:22:39.506] Timer tick, asking Buildbucket for the build status
[09:22:39.584] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:39.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:152:0) after 1m0s
[09:23:39.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:152:0)
[09:23:39.811] Timer tick, asking Buildbucket for the build status
[09:23:39.948] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:39.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:153:0) after 1m0s
[09:24:39.943] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:153:0)
[09:24:39.943] Timer tick, asking Buildbucket for the build status
[09:24:40.083] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:40.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:154:0) after 1m0s
[09:25:40.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:154:0)
[09:25:40.127] Timer tick, asking Buildbucket for the build status
[09:25:40.433] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:40.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:155:0) after 1m0s
[09:26:40.609] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:155:0)
[09:26:40.609] Timer tick, asking Buildbucket for the build status
[09:26:40.670] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:40.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:156:0) after 1m0s
[09:27:41.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:156:0)
[09:27:41.144] Timer tick, asking Buildbucket for the build status
[09:27:41.206] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:41.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:157:0) after 1m0s
[09:28:41.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:157:0)
[09:28:41.306] Timer tick, asking Buildbucket for the build status
[09:28:41.413] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:41.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:158:0) after 1m0s
[09:29:41.767] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:158:0)
[09:29:41.767] Timer tick, asking Buildbucket for the build status
[09:29:41.878] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:41.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:159:0) after 1m0s
[09:30:42.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:159:0)
[09:30:42.227] Timer tick, asking Buildbucket for the build status
[09:30:42.642] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:42.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:160:0) after 1m0s
[09:31:42.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:160:0)
[09:31:42.802] Timer tick, asking Buildbucket for the build status
[09:31:42.876] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:42.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:161:0) after 1m0s
[09:32:43.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:161:0)
[09:32:43.012] Timer tick, asking Buildbucket for the build status
[09:32:43.116] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:43.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:162:0) after 1m0s
[09:33:43.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:162:0)
[09:33:43.173] Timer tick, asking Buildbucket for the build status
[09:33:43.365] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:43.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:163:0) after 1m0s
[09:34:43.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:163:0)
[09:34:43.513] Timer tick, asking Buildbucket for the build status
[09:34:43.572] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:43.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:164:0) after 1m0s
[09:35:43.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:164:0)
[09:35:43.921] Timer tick, asking Buildbucket for the build status
[09:35:44.019] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:44.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:165:0) after 1m0s
[09:36:44.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:165:0)
[09:36:44.260] Timer tick, asking Buildbucket for the build status
[09:36:44.361] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:44.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:166:0) after 1m0s
[09:37:44.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:166:0)
[09:37:44.464] Timer tick, asking Buildbucket for the build status
[09:37:44.525] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:44.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:167:0) after 1m0s
[09:38:44.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:167:0)
[09:38:44.573] Timer tick, asking Buildbucket for the build status
[09:38:44.723] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:44.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:168:0) after 1m0s
[09:39:44.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:168:0)
[09:39:44.829] Timer tick, asking Buildbucket for the build status
[09:39:44.931] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:44.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:169:0) after 1m0s
[09:40:44.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:169:0)
[09:40:44.933] Timer tick, asking Buildbucket for the build status
[09:40:44.997] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:44.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:170:0) after 1m0s
[09:41:45.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:170:0)
[09:41:45.112] Timer tick, asking Buildbucket for the build status
[09:41:45.182] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:45.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:171:0) after 1m0s
[09:42:45.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:171:0)
[09:42:45.260] Timer tick, asking Buildbucket for the build status
[09:42:45.346] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:45.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:172:0) after 1m0s
[09:43:45.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:172:0)
[09:43:45.434] Timer tick, asking Buildbucket for the build status
[09:43:45.726] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:45.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:173:0) after 1m0s
[09:44:45.967] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:173:0)
[09:44:45.967] Timer tick, asking Buildbucket for the build status
[09:44:46.142] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:46.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:174:0) after 1m0s
[09:45:46.240] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:174:0)
[09:45:46.240] Timer tick, asking Buildbucket for the build status
[09:45:46.412] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:46.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:175:0) after 1m0s
[09:46:46.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:175:0)
[09:46:46.491] Timer tick, asking Buildbucket for the build status
[09:46:46.557] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:46.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:176:0) after 1m0s
[09:47:46.611] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:176:0)
[09:47:46.611] Timer tick, asking Buildbucket for the build status
[09:47:46.798] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:46.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:177:0) after 1m0s
[09:48:46.895] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:177:0)
[09:48:46.895] Timer tick, asking Buildbucket for the build status
[09:48:46.995] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:46.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:178:0) after 1m0s
[09:49:47.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:178:0)
[09:49:47.052] Timer tick, asking Buildbucket for the build status
[09:49:47.128] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:47.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:179:0) after 1m0s
[09:50:47.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:179:0)
[09:50:47.164] Timer tick, asking Buildbucket for the build status
[09:50:47.718] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:47.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:180:0) after 1m0s
[09:51:47.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:180:0)
[09:51:47.757] Timer tick, asking Buildbucket for the build status
[09:51:47.811] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:47.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:181:0) after 1m0s
[09:52:47.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:181:0)
[09:52:47.837] Timer tick, asking Buildbucket for the build status
[09:52:47.895] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:47.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:182:0) after 1m0s
[09:53:47.972] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:182:0)
[09:53:47.972] Timer tick, asking Buildbucket for the build status
[09:53:48.067] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:48.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:183:0) after 1m0s
[09:54:48.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:183:0)
[09:54:48.106] Timer tick, asking Buildbucket for the build status
[09:54:48.328] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:48.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:184:0) after 1m0s
[09:55:48.355] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:184:0)
[09:55:48.355] Timer tick, asking Buildbucket for the build status
[09:55:48.417] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:48.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:185:0) after 1m0s
[09:56:48.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:185:0)
[09:56:48.482] Timer tick, asking Buildbucket for the build status
[09:56:48.611] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:48.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:186:0) after 1m0s
[09:57:48.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:186:0)
[09:57:48.652] Timer tick, asking Buildbucket for the build status
[09:57:48.780] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:48.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:187:0) after 1m0s
[09:58:48.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:187:0)
[09:58:48.805] Timer tick, asking Buildbucket for the build status
[09:58:48.905] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:48.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:188:0) after 1m0s
[09:59:48.916] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:188:0)
[09:59:48.916] Timer tick, asking Buildbucket for the build status
[09:59:49.100] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:49.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:189:0) after 1m0s
[10:00:49.133] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:189:0)
[10:00:49.133] Timer tick, asking Buildbucket for the build status
[10:00:49.363] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:49.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:190:0) after 1m0s
[10:01:49.353] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:190:0)
[10:01:49.353] Timer tick, asking Buildbucket for the build status
[10:01:49.441] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:49.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:191:0) after 1m0s
[10:02:49.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:191:0)
[10:02:49.516] Timer tick, asking Buildbucket for the build status
[10:02:49.586] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:49.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:192:0) after 1m0s
[10:03:49.698] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:192:0)
[10:03:49.698] Timer tick, asking Buildbucket for the build status
[10:03:50.127] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:50.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:193:0) after 1m0s
[10:04:50.197] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:193:0)
[10:04:50.197] Timer tick, asking Buildbucket for the build status
[10:04:50.254] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:50.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:194:0) after 1m0s
[10:05:50.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:194:0)
[10:05:50.335] Timer tick, asking Buildbucket for the build status
[10:05:51.105] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:51.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:195:0) after 1m0s
[10:06:51.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:195:0)
[10:06:51.169] Timer tick, asking Buildbucket for the build status
[10:06:51.237] Build 8856428339215205440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:51.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021858401254915520:196:0) after 1m0s
[10:06:54.419] Received PubSub notification, asking Buildbucket for the build status
[10:06:54.526] Build 8856428339215205440: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:06:54.526] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612260413593590",
"created_by": "project:chromium",
"created_ts": "1612248780005234",
"id": "8856428339215205440",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"127c5d89d0691fb959bd409cf1f3ad094af5f4f6\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"127c5d89d0691fb959bd409cf1f3ad094af5f4f6\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-2-ksg6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1517, \"got_angle_revision\": \"729139c9f0233dcbbdb50529ed3681b314250ba1\", \"got_dawn_revision\": \"71b811b4c9901d33f5e3d66002a5265908939b70\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"127c5d89d0691fb959bd409cf1f3ad094af5f4f6\", \"got_revision_cp\": \"refs/heads/master@{#849476}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"079f7c53751ac589cf05880522b3fa2fcb5b5645\", \"got_v8_revision_cp\": \"refs/heads/9.0.109@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"127c5d89d0691fb959bd409cf1f3ad094af5f4f6\"}, \"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-2-ksg6\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5673-2413400\"], \"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": "1612248825914182",
"status": "COMPLETED",
"status_changed_ts": "1612260413974010",
"tags": [
"build_address:luci.chromium.ci/win-official/1517",
"builder:win-official",
"buildset:commit/git/127c5d89d0691fb959bd409cf1f3ad094af5f4f6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/127c5d89d0691fb959bd409cf1f3ad094af5f4f6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021858401254915520",
"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/8856428339215205440/+/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:517a55b366fd5610",
"user_agent:luci-scheduler"
],
"updated_ts": "1612260414005832",
"url": "https://ci.chromium.org/b/8856428339215205440",
"utcnow_ts": "1612260414519134"
}
}
[10:06:54.526] Invocation finished in 3h13m55.849179706s with status SUCCEEDED