[08:56:33.233] New invocation is queued and will start shortly
[08:56:34.544] Starting the invocation (attempt 1)
[08:56:34.590] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:56:34.591] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:56:34.591] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9033265844321104096",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a7d72757624ece89b5e6c98d25c73f841a2844dc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9033265844321104096",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/a7d72757624ece89b5e6c98d25c73f841a2844dc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7d72757624ece89b5e6c98d25c73f841a2844dc",
"gitiles_ref:refs/heads/master"
]
}
[08:56:35.329] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1601369794705682",
"id": "8867835782105691280",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7d72757624ece89b5e6c98d25c73f841a2844dc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601369795156230",
"tags": [
"build_address:luci.chromium.ci/win-official/689",
"builder:win-official",
"buildset:commit/git/a7d72757624ece89b5e6c98d25c73f841a2844dc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7d72757624ece89b5e6c98d25c73f841a2844dc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033265844321104096",
"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/8867835782105691280/+/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": "1601369795156410",
"url": "https://ci.chromium.org/b/8867835782105691280",
"utcnow_ts": "1601369795310594"
}
}
[08:56:35.329] Task URL: https://ci.chromium.org/b/8867835782105691280
[08:56:35.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:2:0) after 1m0s
[08:56:44.216] Received PubSub notification, asking Buildbucket for the build status
[08:56:44.322] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:35.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:2:0)
[08:57:35.482] Timer tick, asking Buildbucket for the build status
[08:57:35.573] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:35.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:4:0) after 1m0s
[08:58:35.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:4:0)
[08:58:35.759] Timer tick, asking Buildbucket for the build status
[08:58:35.902] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:35.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:5:0) after 1m0s
[08:59:36.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:5:0)
[08:59:36.053] Timer tick, asking Buildbucket for the build status
[08:59:36.144] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:36.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:6:0) after 1m0s
[09:00:36.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:6:0)
[09:00:36.223] Timer tick, asking Buildbucket for the build status
[09:00:37.465] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:37.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:7:0) after 1m0s
[09:01:37.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:7:0)
[09:01:37.507] Timer tick, asking Buildbucket for the build status
[09:01:37.618] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:37.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:8:0) after 1m0s
[09:02:37.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:8:0)
[09:02:37.811] Timer tick, asking Buildbucket for the build status
[09:02:37.982] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:37.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:9:0) after 1m0s
[09:03:38.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:9:0)
[09:03:38.145] Timer tick, asking Buildbucket for the build status
[09:03:38.624] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:38.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:10:0) after 1m0s
[09:04:38.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:10:0)
[09:04:38.645] Timer tick, asking Buildbucket for the build status
[09:04:38.842] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:38.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:11:0) after 1m0s
[09:05:38.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:11:0)
[09:05:38.880] Timer tick, asking Buildbucket for the build status
[09:05:39.418] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:39.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:12:0) after 1m0s
[09:06:39.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:12:0)
[09:06:39.442] Timer tick, asking Buildbucket for the build status
[09:06:40.233] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:40.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:13:0) after 1m0s
[09:07:40.363] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:13:0)
[09:07:40.363] Timer tick, asking Buildbucket for the build status
[09:07:40.488] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:40.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:14:0) after 1m0s
[09:08:40.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:14:0)
[09:08:40.516] Timer tick, asking Buildbucket for the build status
[09:08:40.633] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:40.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:15:0) after 1m0s
[09:09:40.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:15:0)
[09:09:40.648] Timer tick, asking Buildbucket for the build status
[09:09:40.826] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:40.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:16:0) after 1m0s
[09:10:40.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:16:0)
[09:10:40.856] Timer tick, asking Buildbucket for the build status
[09:10:41.121] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:41.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:17:0) after 1m0s
[09:11:41.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:17:0)
[09:11:41.168] Timer tick, asking Buildbucket for the build status
[09:11:41.320] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:41.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:18:0) after 1m0s
[09:12:41.374] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:18:0)
[09:12:41.374] Timer tick, asking Buildbucket for the build status
[09:12:41.472] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:41.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:19:0) after 1m0s
[09:13:41.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:19:0)
[09:13:41.481] Timer tick, asking Buildbucket for the build status
[09:13:41.612] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:41.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:20:0) after 1m0s
[09:14:41.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:20:0)
[09:14:41.669] Timer tick, asking Buildbucket for the build status
[09:14:41.775] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:41.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:21:0) after 1m0s
[09:15:41.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:21:0)
[09:15:41.814] Timer tick, asking Buildbucket for the build status
[09:15:42.244] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:42.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:22:0) after 1m0s
[09:16:42.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:22:0)
[09:16:42.364] Timer tick, asking Buildbucket for the build status
[09:16:42.484] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:42.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:23:0) after 1m0s
[09:17:42.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:23:0)
[09:17:42.517] Timer tick, asking Buildbucket for the build status
[09:17:42.593] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:42.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:24:0) after 1m0s
[09:18:42.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:24:0)
[09:18:42.663] Timer tick, asking Buildbucket for the build status
[09:18:42.806] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:42.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:25:0) after 1m0s
[09:19:42.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:25:0)
[09:19:42.799] Timer tick, asking Buildbucket for the build status
[09:19:43.422] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:43.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:26:0) after 1m0s
[09:20:43.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:26:0)
[09:20:43.537] Timer tick, asking Buildbucket for the build status
[09:20:43.617] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:43.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:27:0) after 1m0s
[09:21:43.680] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:27:0)
[09:21:43.680] Timer tick, asking Buildbucket for the build status
[09:21:43.797] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:43.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:28:0) after 1m0s
[09:22:43.865] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:28:0)
[09:22:43.865] Timer tick, asking Buildbucket for the build status
[09:22:43.936] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:43.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:29:0) after 1m0s
[09:23:43.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:29:0)
[09:23:43.970] Timer tick, asking Buildbucket for the build status
[09:23:44.196] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:44.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:30:0) after 1m0s
[09:24:44.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:30:0)
[09:24:44.227] Timer tick, asking Buildbucket for the build status
[09:24:44.292] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:44.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:31:0) after 1m0s
[09:25:44.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:31:0)
[09:25:44.318] Timer tick, asking Buildbucket for the build status
[09:25:44.502] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:44.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:32:0) after 1m0s
[09:26:44.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:32:0)
[09:26:44.507] Timer tick, asking Buildbucket for the build status
[09:26:44.619] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:44.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:33:0) after 1m0s
[09:27:44.690] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:33:0)
[09:27:44.690] Timer tick, asking Buildbucket for the build status
[09:27:44.805] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:44.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:34:0) after 1m0s
[09:28:44.874] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:34:0)
[09:28:44.874] Timer tick, asking Buildbucket for the build status
[09:28:44.964] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:44.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:35:0) after 1m0s
[09:29:44.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:35:0)
[09:29:44.994] Timer tick, asking Buildbucket for the build status
[09:29:45.142] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:45.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:36:0) after 1m0s
[09:30:45.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:36:0)
[09:30:45.172] Timer tick, asking Buildbucket for the build status
[09:30:47.481] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:47.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:37:0) after 1m0s
[09:31:48.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:37:0)
[09:31:48.029] Timer tick, asking Buildbucket for the build status
[09:31:48.268] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:48.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:38:0) after 1m0s
[09:32:48.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:38:0)
[09:32:48.844] Timer tick, asking Buildbucket for the build status
[09:32:49.182] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:49.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:39:0) after 1m0s
[09:33:49.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:39:0)
[09:33:49.342] Timer tick, asking Buildbucket for the build status
[09:33:49.430] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:49.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:40:0) after 1m0s
[09:34:49.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:40:0)
[09:34:49.593] Timer tick, asking Buildbucket for the build status
[09:34:49.665] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:49.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:41:0) after 1m0s
[09:35:49.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:41:0)
[09:35:49.896] Timer tick, asking Buildbucket for the build status
[09:35:49.970] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:49.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:42:0) after 1m0s
[09:36:50.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:42:0)
[09:36:50.106] Timer tick, asking Buildbucket for the build status
[09:36:50.957] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:50.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:43:0) after 1m0s
[09:37:51.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:43:0)
[09:37:51.802] Timer tick, asking Buildbucket for the build status
[09:37:51.873] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:51.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:44:0) after 1m0s
[09:38:52.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:44:0)
[09:38:52.106] Timer tick, asking Buildbucket for the build status
[09:38:52.183] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:52.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:45:0) after 1m0s
[09:39:52.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:45:0)
[09:39:52.337] Timer tick, asking Buildbucket for the build status
[09:39:52.440] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:52.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:46:0) after 1m0s
[09:40:52.638] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:46:0)
[09:40:52.638] Timer tick, asking Buildbucket for the build status
[09:40:52.710] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:52.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:47:0) after 1m0s
[09:41:53.670] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:47:0)
[09:41:53.670] Timer tick, asking Buildbucket for the build status
[09:41:54.041] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:54.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:48:0) after 1m0s
[09:42:54.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:48:0)
[09:42:54.268] Timer tick, asking Buildbucket for the build status
[09:42:54.569] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:54.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:49:0) after 1m0s
[09:43:54.862] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:49:0)
[09:43:54.862] Timer tick, asking Buildbucket for the build status
[09:43:54.954] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:54.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:50:0) after 1m0s
[09:44:54.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:50:0)
[09:44:54.994] Timer tick, asking Buildbucket for the build status
[09:44:55.130] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:55.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:51:0) after 1m0s
[09:45:55.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:51:0)
[09:45:55.201] Timer tick, asking Buildbucket for the build status
[09:45:55.262] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:55.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:52:0) after 1m0s
[09:46:55.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:52:0)
[09:46:55.313] Timer tick, asking Buildbucket for the build status
[09:46:55.901] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:55.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:53:0) after 1m0s
[09:47:55.923] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:53:0)
[09:47:55.923] Timer tick, asking Buildbucket for the build status
[09:47:56.082] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:56.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:54:0) after 1m0s
[09:48:56.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:54:0)
[09:48:56.141] Timer tick, asking Buildbucket for the build status
[09:48:56.205] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:56.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:55:0) after 1m0s
[09:49:56.714] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:55:0)
[09:49:56.714] Timer tick, asking Buildbucket for the build status
[09:49:57.330] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:57.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:56:0) after 1m0s
[09:50:57.325] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:56:0)
[09:50:57.325] Timer tick, asking Buildbucket for the build status
[09:50:57.685] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:57.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:57:0) after 1m0s
[09:51:57.749] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:57:0)
[09:51:57.749] Timer tick, asking Buildbucket for the build status
[09:51:58.310] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:58.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:58:0) after 1m0s
[09:52:58.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:58:0)
[09:52:58.407] Timer tick, asking Buildbucket for the build status
[09:52:58.486] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:58.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:59:0) after 1m0s
[09:53:58.557] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:59:0)
[09:53:58.557] Timer tick, asking Buildbucket for the build status
[09:53:58.935] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:58.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:60:0) after 1m0s
[09:54:59.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:60:0)
[09:54:59.239] Timer tick, asking Buildbucket for the build status
[09:54:59.654] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:59.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:61:0) after 1m0s
[09:56:00.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:61:0)
[09:56:00.985] Timer tick, asking Buildbucket for the build status
[09:56:01.094] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:01.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:62:0) after 1m0s
[09:57:02.433] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:62:0)
[09:57:02.433] Timer tick, asking Buildbucket for the build status
[09:57:02.614] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:02.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:63:0) after 1m0s
[09:58:02.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:63:0)
[09:58:02.598] Timer tick, asking Buildbucket for the build status
[09:58:02.673] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:02.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:64:0) after 1m0s
[09:59:02.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:64:0)
[09:59:02.805] Timer tick, asking Buildbucket for the build status
[09:59:02.989] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:02.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:65:0) after 1m0s
[10:00:03.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:65:0)
[10:00:03.228] Timer tick, asking Buildbucket for the build status
[10:00:03.305] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:03.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:66:0) after 1m0s
[10:01:03.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:66:0)
[10:01:03.584] Timer tick, asking Buildbucket for the build status
[10:01:04.372] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:04.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:67:0) after 1m0s
[10:02:04.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:67:0)
[10:02:04.398] Timer tick, asking Buildbucket for the build status
[10:02:04.714] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:04.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:68:0) after 1m0s
[10:03:04.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:68:0)
[10:03:04.800] Timer tick, asking Buildbucket for the build status
[10:03:04.912] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:04.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:69:0) after 1m0s
[10:04:05.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:69:0)
[10:04:05.026] Timer tick, asking Buildbucket for the build status
[10:04:05.133] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:05.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:70:0) after 1m0s
[10:05:05.216] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:70:0)
[10:05:05.216] Timer tick, asking Buildbucket for the build status
[10:05:05.349] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:05.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:71:0) after 1m0s
[10:06:05.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:71:0)
[10:06:05.405] Timer tick, asking Buildbucket for the build status
[10:06:05.602] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:05.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:72:0) after 1m0s
[10:07:05.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:72:0)
[10:07:05.629] Timer tick, asking Buildbucket for the build status
[10:07:05.986] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:05.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:73:0) after 1m0s
[10:08:06.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:73:0)
[10:08:06.015] Timer tick, asking Buildbucket for the build status
[10:08:06.153] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:06.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:74:0) after 1m0s
[10:09:06.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:74:0)
[10:09:06.177] Timer tick, asking Buildbucket for the build status
[10:09:06.269] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:06.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:75:0) after 1m0s
[10:10:06.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:75:0)
[10:10:06.407] Timer tick, asking Buildbucket for the build status
[10:10:06.492] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:06.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:76:0) after 1m0s
[10:11:06.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:76:0)
[10:11:06.534] Timer tick, asking Buildbucket for the build status
[10:11:06.603] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:06.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:77:0) after 1m0s
[10:12:06.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:77:0)
[10:12:06.651] Timer tick, asking Buildbucket for the build status
[10:12:06.804] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:06.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:78:0) after 1m0s
[10:13:06.857] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:78:0)
[10:13:06.857] Timer tick, asking Buildbucket for the build status
[10:13:06.941] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:06.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:79:0) after 1m0s
[10:14:07.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:79:0)
[10:14:07.042] Timer tick, asking Buildbucket for the build status
[10:14:07.467] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:07.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:80:0) after 1m0s
[10:15:07.492] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:80:0)
[10:15:07.492] Timer tick, asking Buildbucket for the build status
[10:15:07.787] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:07.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:81:0) after 1m0s
[10:16:08.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:81:0)
[10:16:08.747] Timer tick, asking Buildbucket for the build status
[10:16:08.841] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:08.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:82:0) after 1m0s
[10:17:08.901] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:82:0)
[10:17:08.901] Timer tick, asking Buildbucket for the build status
[10:17:09.054] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:09.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:83:0) after 1m0s
[10:18:09.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:83:0)
[10:18:09.106] Timer tick, asking Buildbucket for the build status
[10:18:09.398] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:09.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:84:0) after 1m0s
[10:19:09.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:84:0)
[10:19:09.431] Timer tick, asking Buildbucket for the build status
[10:19:09.598] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:09.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:85:0) after 1m0s
[10:20:09.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:85:0)
[10:20:09.707] Timer tick, asking Buildbucket for the build status
[10:20:09.840] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:09.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:86:0) after 1m0s
[10:21:09.860] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:86:0)
[10:21:09.860] Timer tick, asking Buildbucket for the build status
[10:21:10.039] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:10.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:87:0) after 1m0s
[10:22:10.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:87:0)
[10:22:10.075] Timer tick, asking Buildbucket for the build status
[10:22:10.405] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:10.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:88:0) after 1m0s
[10:23:10.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:88:0)
[10:23:10.491] Timer tick, asking Buildbucket for the build status
[10:23:10.640] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:10.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:89:0) after 1m0s
[10:24:10.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:89:0)
[10:24:10.682] Timer tick, asking Buildbucket for the build status
[10:24:10.865] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:10.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:90:0) after 1m0s
[10:25:10.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:90:0)
[10:25:10.953] Timer tick, asking Buildbucket for the build status
[10:25:11.070] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:11.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:91:0) after 1m0s
[10:26:11.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:91:0)
[10:26:11.117] Timer tick, asking Buildbucket for the build status
[10:26:11.501] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:11.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:92:0) after 1m0s
[10:27:11.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:92:0)
[10:27:11.511] Timer tick, asking Buildbucket for the build status
[10:27:12.394] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:12.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:93:0) after 1m0s
[10:28:12.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:93:0)
[10:28:12.444] Timer tick, asking Buildbucket for the build status
[10:28:12.728] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:12.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:94:0) after 1m0s
[10:29:12.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:94:0)
[10:29:12.754] Timer tick, asking Buildbucket for the build status
[10:29:13.032] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:13.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:95:0) after 1m0s
[10:30:13.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:95:0)
[10:30:13.068] Timer tick, asking Buildbucket for the build status
[10:30:13.232] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:13.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:96:0) after 1m0s
[10:31:13.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:96:0)
[10:31:13.292] Timer tick, asking Buildbucket for the build status
[10:31:13.529] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:13.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:97:0) after 1m0s
[10:32:13.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:97:0)
[10:32:13.707] Timer tick, asking Buildbucket for the build status
[10:32:14.155] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:14.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:98:0) after 1m0s
[10:33:14.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:98:0)
[10:33:14.236] Timer tick, asking Buildbucket for the build status
[10:33:14.321] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:14.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:99:0) after 1m0s
[10:34:14.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:99:0)
[10:34:14.356] Timer tick, asking Buildbucket for the build status
[10:34:14.426] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:14.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:100:0) after 1m0s
[10:35:14.443] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:100:0)
[10:35:14.443] Timer tick, asking Buildbucket for the build status
[10:35:14.564] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:14.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:101:0) after 1m0s
[10:36:14.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:101:0)
[10:36:14.600] Timer tick, asking Buildbucket for the build status
[10:36:14.729] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:14.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:102:0) after 1m0s
[10:37:14.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:102:0)
[10:37:14.788] Timer tick, asking Buildbucket for the build status
[10:37:15.286] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:15.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:103:0) after 1m0s
[10:38:15.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:103:0)
[10:38:15.302] Timer tick, asking Buildbucket for the build status
[10:38:15.387] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:15.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:104:0) after 1m0s
[10:39:15.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:104:0)
[10:39:15.432] Timer tick, asking Buildbucket for the build status
[10:39:15.511] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:15.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:105:0) after 1m0s
[10:40:16.097] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:105:0)
[10:40:16.097] Timer tick, asking Buildbucket for the build status
[10:40:16.210] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:16.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:106:0) after 1m0s
[10:41:16.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:106:0)
[10:41:16.211] Timer tick, asking Buildbucket for the build status
[10:41:18.677] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:18.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:107:0) after 1m0s
[10:42:18.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:107:0)
[10:42:18.715] Timer tick, asking Buildbucket for the build status
[10:42:18.849] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:18.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:108:0) after 1m0s
[10:43:18.914] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:108:0)
[10:43:18.914] Timer tick, asking Buildbucket for the build status
[10:43:19.040] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:19.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:109:0) after 1m0s
[10:44:19.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:109:0)
[10:44:19.086] Timer tick, asking Buildbucket for the build status
[10:44:19.281] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:19.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:110:0) after 1m0s
[10:45:19.382] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:110:0)
[10:45:19.382] Timer tick, asking Buildbucket for the build status
[10:45:19.597] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:19.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:111:0) after 1m0s
[10:46:19.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:111:0)
[10:46:19.648] Timer tick, asking Buildbucket for the build status
[10:46:19.712] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:19.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:112:0) after 1m0s
[10:47:19.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:112:0)
[10:47:19.784] Timer tick, asking Buildbucket for the build status
[10:47:19.892] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:19.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:113:0) after 1m0s
[10:48:19.928] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:113:0)
[10:48:19.928] Timer tick, asking Buildbucket for the build status
[10:48:20.136] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:20.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:114:0) after 1m0s
[10:49:20.180] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:114:0)
[10:49:20.180] Timer tick, asking Buildbucket for the build status
[10:49:20.269] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:20.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:115:0) after 1m0s
[10:50:20.479] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:115:0)
[10:50:20.479] Timer tick, asking Buildbucket for the build status
[10:50:20.638] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:20.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:116:0) after 1m0s
[10:51:20.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:116:0)
[10:51:20.686] Timer tick, asking Buildbucket for the build status
[10:51:20.902] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:20.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:117:0) after 1m0s
[10:52:21.007] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:117:0)
[10:52:21.007] Timer tick, asking Buildbucket for the build status
[10:52:21.135] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:21.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:118:0) after 1m0s
[10:53:21.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:118:0)
[10:53:21.214] Timer tick, asking Buildbucket for the build status
[10:53:21.291] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:21.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:119:0) after 1m0s
[10:54:21.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:119:0)
[10:54:21.416] Timer tick, asking Buildbucket for the build status
[10:54:21.493] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:21.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:120:0) after 1m0s
[10:55:21.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:120:0)
[10:55:21.588] Timer tick, asking Buildbucket for the build status
[10:55:21.977] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:21.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:121:0) after 1m0s
[10:56:22.084] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:121:0)
[10:56:22.084] Timer tick, asking Buildbucket for the build status
[10:56:22.565] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:22.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:122:0) after 1m0s
[10:57:22.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:122:0)
[10:57:22.719] Timer tick, asking Buildbucket for the build status
[10:57:23.024] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:23.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:123:0) after 1m0s
[10:58:23.149] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:123:0)
[10:58:23.149] Timer tick, asking Buildbucket for the build status
[10:58:23.212] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:23.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:124:0) after 1m0s
[10:59:23.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:124:0)
[10:59:23.229] Timer tick, asking Buildbucket for the build status
[10:59:23.309] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:23.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:125:0) after 1m0s
[11:00:23.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:125:0)
[11:00:23.337] Timer tick, asking Buildbucket for the build status
[11:00:23.434] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:23.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:126:0) after 1m0s
[11:01:23.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:126:0)
[11:01:23.563] Timer tick, asking Buildbucket for the build status
[11:01:24.273] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:24.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:127:0) after 1m0s
[11:02:24.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:127:0)
[11:02:24.518] Timer tick, asking Buildbucket for the build status
[11:02:24.665] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:24.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:128:0) after 1m0s
[11:03:24.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:128:0)
[11:03:24.759] Timer tick, asking Buildbucket for the build status
[11:03:24.841] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:24.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:129:0) after 1m0s
[11:04:24.852] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:129:0)
[11:04:24.852] Timer tick, asking Buildbucket for the build status
[11:04:24.936] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:24.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:130:0) after 1m0s
[11:05:24.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:130:0)
[11:05:24.978] Timer tick, asking Buildbucket for the build status
[11:05:25.296] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:25.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:131:0) after 1m0s
[11:06:25.363] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:131:0)
[11:06:25.363] Timer tick, asking Buildbucket for the build status
[11:06:25.645] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:25.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:132:0) after 1m0s
[11:07:25.681] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:132:0)
[11:07:25.681] Timer tick, asking Buildbucket for the build status
[11:07:26.303] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:26.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:133:0) after 1m0s
[11:08:26.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:133:0)
[11:08:26.354] Timer tick, asking Buildbucket for the build status
[11:08:26.505] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:26.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:134:0) after 1m0s
[11:09:26.591] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:134:0)
[11:09:26.591] Timer tick, asking Buildbucket for the build status
[11:09:26.660] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:26.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:135:0) after 1m0s
[11:10:26.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:135:0)
[11:10:26.720] Timer tick, asking Buildbucket for the build status
[11:10:26.780] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:26.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:136:0) after 1m0s
[11:11:26.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:136:0)
[11:11:26.850] Timer tick, asking Buildbucket for the build status
[11:11:26.975] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:26.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:137:0) after 1m0s
[11:12:27.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:137:0)
[11:12:27.021] Timer tick, asking Buildbucket for the build status
[11:12:27.351] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:27.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:138:0) after 1m0s
[11:13:27.408] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:138:0)
[11:13:27.408] Timer tick, asking Buildbucket for the build status
[11:13:27.525] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:27.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:139:0) after 1m0s
[11:14:27.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:139:0)
[11:14:27.568] Timer tick, asking Buildbucket for the build status
[11:14:27.654] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:27.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:140:0) after 1m0s
[11:15:27.716] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:140:0)
[11:15:27.717] Timer tick, asking Buildbucket for the build status
[11:15:27.785] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:27.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:141:0) after 1m0s
[11:16:27.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:141:0)
[11:16:27.851] Timer tick, asking Buildbucket for the build status
[11:16:27.991] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:27.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:142:0) after 1m0s
[11:17:28.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:142:0)
[11:17:28.009] Timer tick, asking Buildbucket for the build status
[11:17:28.144] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:28.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:143:0) after 1m0s
[11:18:28.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:143:0)
[11:18:28.416] Timer tick, asking Buildbucket for the build status
[11:18:28.586] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:28.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:144:0) after 1m0s
[11:19:28.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:144:0)
[11:19:28.639] Timer tick, asking Buildbucket for the build status
[11:19:29.475] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:29.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:145:0) after 1m0s
[11:20:29.496] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:145:0)
[11:20:29.496] Timer tick, asking Buildbucket for the build status
[11:20:29.662] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:29.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:146:0) after 1m0s
[11:21:29.771] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:146:0)
[11:21:29.771] Timer tick, asking Buildbucket for the build status
[11:21:29.863] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:29.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:147:0) after 1m0s
[11:22:29.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:147:0)
[11:22:29.890] Timer tick, asking Buildbucket for the build status
[11:22:30.068] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:30.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:148:0) after 1m0s
[11:23:30.166] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:148:0)
[11:23:30.166] Timer tick, asking Buildbucket for the build status
[11:23:30.403] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:30.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:149:0) after 1m0s
[11:24:30.478] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:149:0)
[11:24:30.478] Timer tick, asking Buildbucket for the build status
[11:24:30.931] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:30.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:150:0) after 1m0s
[11:25:30.966] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:150:0)
[11:25:30.966] Timer tick, asking Buildbucket for the build status
[11:25:31.043] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:31.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:151:0) after 1m0s
[11:26:31.095] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:151:0)
[11:26:31.095] Timer tick, asking Buildbucket for the build status
[11:26:31.186] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:31.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:152:0) after 1m0s
[11:27:31.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:152:0)
[11:27:31.295] Timer tick, asking Buildbucket for the build status
[11:27:31.433] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:31.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:153:0) after 1m0s
[11:28:31.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:153:0)
[11:28:31.469] Timer tick, asking Buildbucket for the build status
[11:28:31.549] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:31.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:154:0) after 1m0s
[11:29:31.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:154:0)
[11:29:31.599] Timer tick, asking Buildbucket for the build status
[11:29:31.675] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:31.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:155:0) after 1m0s
[11:30:31.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:155:0)
[11:30:31.773] Timer tick, asking Buildbucket for the build status
[11:30:31.858] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:31.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:156:0) after 1m0s
[11:31:31.929] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:156:0)
[11:31:31.929] Timer tick, asking Buildbucket for the build status
[11:31:32.006] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:32.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:157:0) after 1m0s
[11:32:32.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:157:0)
[11:32:32.109] Timer tick, asking Buildbucket for the build status
[11:32:32.216] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:32.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:158:0) after 1m0s
[11:33:32.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:158:0)
[11:33:32.302] Timer tick, asking Buildbucket for the build status
[11:33:32.418] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:32.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:159:0) after 1m0s
[11:34:32.445] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:159:0)
[11:34:32.445] Timer tick, asking Buildbucket for the build status
[11:34:32.633] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:32.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:160:0) after 1m0s
[11:35:33.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:160:0)
[11:35:33.896] Timer tick, asking Buildbucket for the build status
[11:35:34.074] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:34.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:161:0) after 1m0s
[11:36:34.092] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:161:0)
[11:36:34.092] Timer tick, asking Buildbucket for the build status
[11:36:34.405] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:34.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:162:0) after 1m0s
[11:37:34.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:162:0)
[11:37:34.547] Timer tick, asking Buildbucket for the build status
[11:37:34.666] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:34.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:163:0) after 1m0s
[11:38:34.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:163:0)
[11:38:34.721] Timer tick, asking Buildbucket for the build status
[11:38:34.794] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:34.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:164:0) after 1m0s
[11:39:34.830] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:164:0)
[11:39:34.830] Timer tick, asking Buildbucket for the build status
[11:39:34.956] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:34.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:165:0) after 1m0s
[11:40:35.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:165:0)
[11:40:35.022] Timer tick, asking Buildbucket for the build status
[11:40:35.145] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:35.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:166:0) after 1m0s
[11:41:35.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:166:0)
[11:41:35.235] Timer tick, asking Buildbucket for the build status
[11:41:35.359] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:35.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:167:0) after 1m0s
[11:42:35.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:167:0)
[11:42:35.369] Timer tick, asking Buildbucket for the build status
[11:42:35.511] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:35.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:168:0) after 1m0s
[11:43:35.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:168:0)
[11:43:35.505] Timer tick, asking Buildbucket for the build status
[11:43:35.570] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:35.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:169:0) after 1m0s
[11:44:35.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:169:0)
[11:44:35.595] Timer tick, asking Buildbucket for the build status
[11:44:35.707] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:35.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:170:0) after 1m0s
[11:45:35.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:170:0)
[11:45:35.728] Timer tick, asking Buildbucket for the build status
[11:45:36.342] Build 8867835782105691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:36.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9033265844321104096:171:0) after 1m0s
[11:46:20.383] Received PubSub notification, asking Buildbucket for the build status
[11:46:20.472] Build 8867835782105691280: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:46:20.472] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1601379978737452",
"created_by": "project:chromium",
"created_ts": "1601369794705682",
"id": "8867835782105691280",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7d72757624ece89b5e6c98d25c73f841a2844dc\"}}",
"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\": \"a7d72757624ece89b5e6c98d25c73f841a2844dc\", \"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-0-dy2m\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 689, \"got_angle_revision\": \"2663e6012911a3dc3e978a41f7c52d5c12c48c69\", \"got_dawn_revision\": \"62139fcca727c3592b65be32c0146ca1a3a195cc\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"a7d72757624ece89b5e6c98d25c73f841a2844dc\", \"got_revision_cp\": \"refs/heads/master@{#811613}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"44be0942f9a8b965f877f294f1c2d8fb15f88ec6\", \"got_v8_revision\": \"95c0414b1889f2d761e26024a8a040d647f81854\", \"got_v8_revision_cp\": \"refs/heads/8.7.214@{#1}\", \"got_webrtc_revision\": \"e39b378d4a921e9982f39ebb02a6145afb653956\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32216}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7d72757624ece89b5e6c98d25c73f841a2844dc\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-0-dy2m\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5559-9b15cc9\"], \"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": "1601369803359973",
"status": "COMPLETED",
"status_changed_ts": "1601379979374504",
"tags": [
"build_address:luci.chromium.ci/win-official/689",
"builder:win-official",
"buildset:commit/git/a7d72757624ece89b5e6c98d25c73f841a2844dc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7d72757624ece89b5e6c98d25c73f841a2844dc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033265844321104096",
"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/8867835782105691280/+/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:4ef1e57338b37310",
"user_agent:luci-scheduler"
],
"updated_ts": "1601379979492887",
"url": "https://ci.chromium.org/b/8867835782105691280",
"utcnow_ts": "1601379980458841"
}
}
[11:46:20.473] Invocation finished in 2h49m47.263384443s with status SUCCEEDED