[06:06:15.255] New invocation is queued and will start shortly
[06:06:16.401] Starting the invocation (attempt 1)
[06:06:16.437] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:06:16.437] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:06:16.437] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024307458972948736",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dd13061e37809876a1e2956d59ff6600c30aeef1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9024307458972948736",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/dd13061e37809876a1e2956d59ff6600c30aeef1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd13061e37809876a1e2956d59ff6600c30aeef1",
"gitiles_ref:refs/heads/master"
]
}
[06:06:17.360] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609913176548676",
"id": "8858877396945943776",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd13061e37809876a1e2956d59ff6600c30aeef1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609913177093264",
"tags": [
"build_address:luci.chromium.ci/win-official/1319",
"builder:win-official",
"buildset:commit/git/dd13061e37809876a1e2956d59ff6600c30aeef1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd13061e37809876a1e2956d59ff6600c30aeef1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024307458972948736",
"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/8858877396945943776/+/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": "1609913177093512",
"url": "https://ci.chromium.org/b/8858877396945943776",
"utcnow_ts": "1609913177351588"
}
}
[06:06:17.360] Task URL: https://ci.chromium.org/b/8858877396945943776
[06:06:17.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:2:0) after 1m0s
[06:06:50.385] Received PubSub notification, asking Buildbucket for the build status
[06:06:50.458] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:17.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:2:0)
[06:07:17.361] Timer tick, asking Buildbucket for the build status
[06:07:17.470] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:17.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:4:0) after 1m0s
[06:08:17.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:4:0)
[06:08:17.507] Timer tick, asking Buildbucket for the build status
[06:08:17.614] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:17.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:5:0) after 1m0s
[06:09:17.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:5:0)
[06:09:17.616] Timer tick, asking Buildbucket for the build status
[06:09:18.046] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:18.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:6:0) after 1m0s
[06:10:18.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:6:0)
[06:10:18.076] Timer tick, asking Buildbucket for the build status
[06:10:18.233] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:18.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:7:0) after 1m0s
[06:11:18.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:7:0)
[06:11:18.247] Timer tick, asking Buildbucket for the build status
[06:11:18.377] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:18.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:8:0) after 1m0s
[06:12:18.446] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:8:0)
[06:12:18.446] Timer tick, asking Buildbucket for the build status
[06:12:18.524] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:18.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:9:0) after 1m0s
[06:13:18.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:9:0)
[06:13:18.532] Timer tick, asking Buildbucket for the build status
[06:13:18.626] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:18.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:10:0) after 1m0s
[06:14:18.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:10:0)
[06:14:18.645] Timer tick, asking Buildbucket for the build status
[06:14:18.739] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:18.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:11:0) after 1m0s
[06:15:18.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:11:0)
[06:15:18.786] Timer tick, asking Buildbucket for the build status
[06:15:19.945] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:19.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:12:0) after 1m0s
[06:16:20.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:12:0)
[06:16:20.003] Timer tick, asking Buildbucket for the build status
[06:16:20.134] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:20.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:13:0) after 1m0s
[06:17:20.120] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:13:0)
[06:17:20.120] Timer tick, asking Buildbucket for the build status
[06:17:20.279] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:20.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:14:0) after 1m0s
[06:18:20.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:14:0)
[06:18:20.384] Timer tick, asking Buildbucket for the build status
[06:18:20.466] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:20.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:15:0) after 1m0s
[06:19:20.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:15:0)
[06:19:20.539] Timer tick, asking Buildbucket for the build status
[06:19:20.623] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:20.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:16:0) after 1m0s
[06:20:20.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:16:0)
[06:20:20.685] Timer tick, asking Buildbucket for the build status
[06:20:20.887] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:20.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:17:0) after 1m0s
[06:21:20.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:17:0)
[06:21:20.920] Timer tick, asking Buildbucket for the build status
[06:21:21.043] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:21.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:18:0) after 1m0s
[06:22:21.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:18:0)
[06:22:21.116] Timer tick, asking Buildbucket for the build status
[06:22:21.337] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:21.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:19:0) after 1m0s
[06:23:21.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:19:0)
[06:23:21.361] Timer tick, asking Buildbucket for the build status
[06:23:21.534] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:21.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:20:0) after 1m0s
[06:24:21.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:20:0)
[06:24:21.584] Timer tick, asking Buildbucket for the build status
[06:24:21.647] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:21.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:21:0) after 1m0s
[06:25:21.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:21:0)
[06:25:21.650] Timer tick, asking Buildbucket for the build status
[06:25:21.742] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:21.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:22:0) after 1m0s
[06:26:21.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:22:0)
[06:26:21.788] Timer tick, asking Buildbucket for the build status
[06:26:21.919] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:21.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:23:0) after 1m0s
[06:27:21.950] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:23:0)
[06:27:21.950] Timer tick, asking Buildbucket for the build status
[06:27:22.073] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:22.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:24:0) after 1m0s
[06:28:22.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:24:0)
[06:28:22.105] Timer tick, asking Buildbucket for the build status
[06:28:22.192] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:22.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:25:0) after 1m0s
[06:29:22.198] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:25:0)
[06:29:22.198] Timer tick, asking Buildbucket for the build status
[06:29:22.271] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:22.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:26:0) after 1m0s
[06:30:22.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:26:0)
[06:30:22.327] Timer tick, asking Buildbucket for the build status
[06:30:22.577] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:22.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:27:0) after 1m0s
[06:31:22.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:27:0)
[06:31:22.621] Timer tick, asking Buildbucket for the build status
[06:31:22.717] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:22.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:28:0) after 1m0s
[06:32:22.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:28:0)
[06:32:22.738] Timer tick, asking Buildbucket for the build status
[06:32:22.796] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:22.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:29:0) after 1m0s
[06:33:22.929] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:29:0)
[06:33:22.929] Timer tick, asking Buildbucket for the build status
[06:33:22.998] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:22.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:30:0) after 1m0s
[06:34:23.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:30:0)
[06:34:23.034] Timer tick, asking Buildbucket for the build status
[06:34:23.195] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:23.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:31:0) after 1m0s
[06:35:23.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:31:0)
[06:35:23.227] Timer tick, asking Buildbucket for the build status
[06:35:23.296] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:23.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:32:0) after 1m0s
[06:36:23.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:32:0)
[06:36:23.268] Timer tick, asking Buildbucket for the build status
[06:36:23.473] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:23.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:33:0) after 1m0s
[06:37:23.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:33:0)
[06:37:23.547] Timer tick, asking Buildbucket for the build status
[06:37:23.728] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:23.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:34:0) after 1m0s
[06:38:23.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:34:0)
[06:38:23.721] Timer tick, asking Buildbucket for the build status
[06:38:24.748] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:24.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:35:0) after 1m0s
[06:39:24.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:35:0)
[06:39:24.809] Timer tick, asking Buildbucket for the build status
[06:39:24.958] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:24.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:36:0) after 1m0s
[06:40:24.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:36:0)
[06:40:24.997] Timer tick, asking Buildbucket for the build status
[06:40:25.440] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:25.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:37:0) after 1m0s
[06:41:25.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:37:0)
[06:41:25.438] Timer tick, asking Buildbucket for the build status
[06:41:25.587] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:25.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:38:0) after 1m0s
[06:42:25.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:38:0)
[06:42:25.618] Timer tick, asking Buildbucket for the build status
[06:42:25.687] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:25.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:39:0) after 1m0s
[06:43:25.710] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:39:0)
[06:43:25.710] Timer tick, asking Buildbucket for the build status
[06:43:25.781] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:25.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:40:0) after 1m0s
[06:44:25.806] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:40:0)
[06:44:25.806] Timer tick, asking Buildbucket for the build status
[06:44:25.886] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:25.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:41:0) after 1m0s
[06:45:25.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:41:0)
[06:45:25.942] Timer tick, asking Buildbucket for the build status
[06:45:26.009] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:26.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:42:0) after 1m0s
[06:46:26.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:42:0)
[06:46:26.067] Timer tick, asking Buildbucket for the build status
[06:46:26.344] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:26.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:43:0) after 1m0s
[06:47:26.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:43:0)
[06:47:26.417] Timer tick, asking Buildbucket for the build status
[06:47:26.635] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:26.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:44:0) after 1m0s
[06:48:26.684] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:44:0)
[06:48:26.684] Timer tick, asking Buildbucket for the build status
[06:48:26.972] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:26.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:45:0) after 1m0s
[06:49:26.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:45:0)
[06:49:26.974] Timer tick, asking Buildbucket for the build status
[06:49:27.094] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:27.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:46:0) after 1m0s
[06:50:27.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:46:0)
[06:50:27.127] Timer tick, asking Buildbucket for the build status
[06:50:27.186] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:27.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:47:0) after 1m0s
[06:51:27.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:47:0)
[06:51:27.174] Timer tick, asking Buildbucket for the build status
[06:51:27.608] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:27.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:48:0) after 1m0s
[06:52:27.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:48:0)
[06:52:27.647] Timer tick, asking Buildbucket for the build status
[06:52:27.712] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:27.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:49:0) after 1m0s
[06:53:27.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:49:0)
[06:53:27.804] Timer tick, asking Buildbucket for the build status
[06:53:28.082] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:28.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:50:0) after 1m0s
[06:54:28.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:50:0)
[06:54:28.118] Timer tick, asking Buildbucket for the build status
[06:54:28.427] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:28.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:51:0) after 1m0s
[06:55:28.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:51:0)
[06:55:28.458] Timer tick, asking Buildbucket for the build status
[06:55:28.593] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:28.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:52:0) after 1m0s
[06:56:28.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:52:0)
[06:56:28.602] Timer tick, asking Buildbucket for the build status
[06:56:28.735] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:28.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:53:0) after 1m0s
[06:57:28.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:53:0)
[06:57:28.731] Timer tick, asking Buildbucket for the build status
[06:57:28.811] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:28.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:54:0) after 1m0s
[06:58:28.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:54:0)
[06:58:28.834] Timer tick, asking Buildbucket for the build status
[06:58:28.898] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:28.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:55:0) after 1m0s
[06:59:28.931] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:55:0)
[06:59:28.931] Timer tick, asking Buildbucket for the build status
[06:59:29.021] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:29.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:56:0) after 1m0s
[07:00:29.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:56:0)
[07:00:29.130] Timer tick, asking Buildbucket for the build status
[07:00:29.205] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:29.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:57:0) after 1m0s
[07:01:29.251] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:57:0)
[07:01:29.251] Timer tick, asking Buildbucket for the build status
[07:01:29.438] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:29.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:58:0) after 1m0s
[07:02:29.462] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:58:0)
[07:02:29.462] Timer tick, asking Buildbucket for the build status
[07:02:29.755] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:29.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:59:0) after 1m0s
[07:03:29.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:59:0)
[07:03:29.779] Timer tick, asking Buildbucket for the build status
[07:03:29.844] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:29.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:60:0) after 1m0s
[07:04:29.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:60:0)
[07:04:29.873] Timer tick, asking Buildbucket for the build status
[07:04:30.123] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:30.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:61:0) after 1m0s
[07:05:30.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:61:0)
[07:05:30.209] Timer tick, asking Buildbucket for the build status
[07:05:30.318] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:30.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:62:0) after 1m0s
[07:06:30.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:62:0)
[07:06:30.432] Timer tick, asking Buildbucket for the build status
[07:06:30.570] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:30.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:63:0) after 1m0s
[07:07:30.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:63:0)
[07:07:30.715] Timer tick, asking Buildbucket for the build status
[07:07:30.874] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:30.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:64:0) after 1m0s
[07:08:30.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:64:0)
[07:08:30.957] Timer tick, asking Buildbucket for the build status
[07:08:31.597] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:31.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:65:0) after 1m0s
[07:09:31.605] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:65:0)
[07:09:31.605] Timer tick, asking Buildbucket for the build status
[07:09:34.771] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:34.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:66:0) after 1m0s
[07:10:34.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:66:0)
[07:10:34.808] Timer tick, asking Buildbucket for the build status
[07:10:34.888] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:34.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:67:0) after 1m0s
[07:11:34.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:67:0)
[07:11:34.906] Timer tick, asking Buildbucket for the build status
[07:11:35.065] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:35.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:68:0) after 1m0s
[07:12:35.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:68:0)
[07:12:35.066] Timer tick, asking Buildbucket for the build status
[07:12:35.152] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:35.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:69:0) after 1m0s
[07:13:35.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:69:0)
[07:13:35.219] Timer tick, asking Buildbucket for the build status
[07:13:35.336] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:35.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:70:0) after 1m0s
[07:14:35.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:70:0)
[07:14:35.447] Timer tick, asking Buildbucket for the build status
[07:14:35.633] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:35.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:71:0) after 1m0s
[07:15:35.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:71:0)
[07:15:35.720] Timer tick, asking Buildbucket for the build status
[07:15:35.793] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:35.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:72:0) after 1m0s
[07:16:35.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:72:0)
[07:16:35.775] Timer tick, asking Buildbucket for the build status
[07:16:35.908] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:35.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:73:0) after 1m0s
[07:17:35.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:73:0)
[07:17:35.933] Timer tick, asking Buildbucket for the build status
[07:17:36.069] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:36.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:74:0) after 1m0s
[07:18:36.087] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:74:0)
[07:18:36.087] Timer tick, asking Buildbucket for the build status
[07:18:36.305] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:36.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:75:0) after 1m0s
[07:19:36.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:75:0)
[07:19:36.279] Timer tick, asking Buildbucket for the build status
[07:19:36.524] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:36.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:76:0) after 1m0s
[07:20:36.634] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:76:0)
[07:20:36.634] Timer tick, asking Buildbucket for the build status
[07:20:36.707] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:36.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:77:0) after 1m0s
[07:21:36.791] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:77:0)
[07:21:36.791] Timer tick, asking Buildbucket for the build status
[07:21:36.891] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:36.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:78:0) after 1m0s
[07:22:36.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:78:0)
[07:22:36.919] Timer tick, asking Buildbucket for the build status
[07:22:36.968] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:36.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:79:0) after 1m0s
[07:23:37.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:79:0)
[07:23:37.065] Timer tick, asking Buildbucket for the build status
[07:23:37.135] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:37.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:80:0) after 1m0s
[07:24:37.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:80:0)
[07:24:37.160] Timer tick, asking Buildbucket for the build status
[07:24:37.256] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:37.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:81:0) after 1m0s
[07:25:37.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:81:0)
[07:25:37.256] Timer tick, asking Buildbucket for the build status
[07:25:37.331] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:37.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:82:0) after 1m0s
[07:26:37.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:82:0)
[07:26:37.384] Timer tick, asking Buildbucket for the build status
[07:26:37.556] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:37.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:83:0) after 1m0s
[07:27:37.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:83:0)
[07:27:37.580] Timer tick, asking Buildbucket for the build status
[07:27:37.746] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:37.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:84:0) after 1m0s
[07:28:37.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:84:0)
[07:28:37.756] Timer tick, asking Buildbucket for the build status
[07:28:37.862] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:37.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:85:0) after 1m0s
[07:29:37.884] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:85:0)
[07:29:37.884] Timer tick, asking Buildbucket for the build status
[07:29:37.994] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:37.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:86:0) after 1m0s
[07:30:38.071] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:86:0)
[07:30:38.071] Timer tick, asking Buildbucket for the build status
[07:30:38.153] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:38.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:87:0) after 1m0s
[07:31:38.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:87:0)
[07:31:38.238] Timer tick, asking Buildbucket for the build status
[07:31:38.786] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:38.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:88:0) after 1m0s
[07:32:38.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:88:0)
[07:32:38.822] Timer tick, asking Buildbucket for the build status
[07:32:38.893] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:38.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:89:0) after 1m0s
[07:33:38.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:89:0)
[07:33:38.940] Timer tick, asking Buildbucket for the build status
[07:33:39.034] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:39.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:90:0) after 1m0s
[07:34:39.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:90:0)
[07:34:39.086] Timer tick, asking Buildbucket for the build status
[07:34:39.165] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:39.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:91:0) after 1m0s
[07:35:39.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:91:0)
[07:35:39.223] Timer tick, asking Buildbucket for the build status
[07:35:39.443] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:39.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:92:0) after 1m0s
[07:36:39.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:92:0)
[07:36:39.513] Timer tick, asking Buildbucket for the build status
[07:36:39.624] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:39.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:93:0) after 1m0s
[07:37:39.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:93:0)
[07:37:39.667] Timer tick, asking Buildbucket for the build status
[07:37:39.741] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:39.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:94:0) after 1m0s
[07:38:39.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:94:0)
[07:38:39.784] Timer tick, asking Buildbucket for the build status
[07:38:39.864] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:39.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:95:0) after 1m0s
[07:39:39.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:95:0)
[07:39:39.889] Timer tick, asking Buildbucket for the build status
[07:39:40.713] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:40.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:96:0) after 1m0s
[07:40:40.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:96:0)
[07:40:40.744] Timer tick, asking Buildbucket for the build status
[07:40:44.121] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:44.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:97:0) after 1m0s
[07:41:44.119] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:97:0)
[07:41:44.119] Timer tick, asking Buildbucket for the build status
[07:41:44.348] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:44.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:98:0) after 1m0s
[07:42:44.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:98:0)
[07:42:44.372] Timer tick, asking Buildbucket for the build status
[07:42:44.455] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:44.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:99:0) after 1m0s
[07:43:44.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:99:0)
[07:43:44.491] Timer tick, asking Buildbucket for the build status
[07:43:44.577] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:44.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:100:0) after 1m0s
[07:44:44.611] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:100:0)
[07:44:44.611] Timer tick, asking Buildbucket for the build status
[07:44:44.671] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:44.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:101:0) after 1m0s
[07:45:44.810] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:101:0)
[07:45:44.810] Timer tick, asking Buildbucket for the build status
[07:45:44.925] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:44.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:102:0) after 1m0s
[07:46:45.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:102:0)
[07:46:45.009] Timer tick, asking Buildbucket for the build status
[07:46:45.153] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:45.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:103:0) after 1m0s
[07:47:45.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:103:0)
[07:47:45.270] Timer tick, asking Buildbucket for the build status
[07:47:45.395] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:45.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:104:0) after 1m0s
[07:48:45.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:104:0)
[07:48:45.608] Timer tick, asking Buildbucket for the build status
[07:48:45.880] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:45.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:105:0) after 1m0s
[07:49:46.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:105:0)
[07:49:46.026] Timer tick, asking Buildbucket for the build status
[07:49:46.294] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:46.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:106:0) after 1m0s
[07:50:46.374] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:106:0)
[07:50:46.374] Timer tick, asking Buildbucket for the build status
[07:50:46.477] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:46.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:107:0) after 1m0s
[07:51:46.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:107:0)
[07:51:46.525] Timer tick, asking Buildbucket for the build status
[07:51:47.009] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:47.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:108:0) after 1m0s
[07:52:47.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:108:0)
[07:52:47.135] Timer tick, asking Buildbucket for the build status
[07:52:47.772] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:47.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:109:0) after 1m0s
[07:53:47.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:109:0)
[07:53:47.786] Timer tick, asking Buildbucket for the build status
[07:53:48.087] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:48.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:110:0) after 1m0s
[07:54:48.139] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:110:0)
[07:54:48.139] Timer tick, asking Buildbucket for the build status
[07:54:52.192] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:52.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:111:0) after 1m0s
[07:55:52.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:111:0)
[07:55:52.298] Timer tick, asking Buildbucket for the build status
[07:55:52.408] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:52.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:112:0) after 1m0s
[07:56:52.459] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:112:0)
[07:56:52.459] Timer tick, asking Buildbucket for the build status
[07:56:52.607] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:52.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:113:0) after 1m0s
[07:57:52.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:113:0)
[07:57:52.731] Timer tick, asking Buildbucket for the build status
[07:57:52.802] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:52.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:114:0) after 1m0s
[07:58:53.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:114:0)
[07:58:53.094] Timer tick, asking Buildbucket for the build status
[07:58:53.158] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:53.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:115:0) after 1m0s
[07:59:53.297] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:115:0)
[07:59:53.297] Timer tick, asking Buildbucket for the build status
[07:59:53.400] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:53.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:116:0) after 1m0s
[08:00:53.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:116:0)
[08:00:53.469] Timer tick, asking Buildbucket for the build status
[08:00:53.726] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:53.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:117:0) after 1m0s
[08:01:53.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:117:0)
[08:01:53.879] Timer tick, asking Buildbucket for the build status
[08:01:54.007] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:54.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:118:0) after 1m0s
[08:02:54.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:118:0)
[08:02:54.167] Timer tick, asking Buildbucket for the build status
[08:02:54.238] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:54.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:119:0) after 1m0s
[08:03:54.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:119:0)
[08:03:54.337] Timer tick, asking Buildbucket for the build status
[08:03:54.468] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:54.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:120:0) after 1m0s
[08:04:55.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:120:0)
[08:04:55.653] Timer tick, asking Buildbucket for the build status
[08:04:55.721] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:55.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:121:0) after 1m0s
[08:05:55.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:121:0)
[08:05:55.958] Timer tick, asking Buildbucket for the build status
[08:05:56.231] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:56.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:122:0) after 1m0s
[08:06:56.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:122:0)
[08:06:56.367] Timer tick, asking Buildbucket for the build status
[08:06:56.514] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:56.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:123:0) after 1m0s
[08:07:56.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:123:0)
[08:07:56.571] Timer tick, asking Buildbucket for the build status
[08:07:56.648] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:56.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:124:0) after 1m0s
[08:08:56.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:124:0)
[08:08:56.832] Timer tick, asking Buildbucket for the build status
[08:08:56.931] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:56.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:125:0) after 1m0s
[08:09:57.001] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:125:0)
[08:09:57.001] Timer tick, asking Buildbucket for the build status
[08:09:57.050] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:57.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:126:0) after 1m0s
[08:10:57.137] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:126:0)
[08:10:57.137] Timer tick, asking Buildbucket for the build status
[08:10:57.299] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:57.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:127:0) after 1m0s
[08:11:57.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:127:0)
[08:11:57.525] Timer tick, asking Buildbucket for the build status
[08:11:57.648] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:57.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:128:0) after 1m0s
[08:12:57.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:128:0)
[08:12:57.734] Timer tick, asking Buildbucket for the build status
[08:12:57.854] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:57.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:129:0) after 1m0s
[08:13:58.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:129:0)
[08:13:58.022] Timer tick, asking Buildbucket for the build status
[08:13:58.161] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:58.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:130:0) after 1m0s
[08:14:58.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:130:0)
[08:14:58.185] Timer tick, asking Buildbucket for the build status
[08:14:58.471] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:58.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:131:0) after 1m0s
[08:15:58.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:131:0)
[08:15:58.502] Timer tick, asking Buildbucket for the build status
[08:15:58.634] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:58.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:132:0) after 1m0s
[08:16:58.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:132:0)
[08:16:58.707] Timer tick, asking Buildbucket for the build status
[08:16:58.984] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:58.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:133:0) after 1m0s
[08:17:58.986] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:133:0)
[08:17:58.986] Timer tick, asking Buildbucket for the build status
[08:17:59.178] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:59.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:134:0) after 1m0s
[08:18:59.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:134:0)
[08:18:59.254] Timer tick, asking Buildbucket for the build status
[08:18:59.378] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:59.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:135:0) after 1m0s
[08:19:59.426] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:135:0)
[08:19:59.426] Timer tick, asking Buildbucket for the build status
[08:19:59.554] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:59.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:136:0) after 1m0s
[08:20:59.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:136:0)
[08:20:59.761] Timer tick, asking Buildbucket for the build status
[08:21:00.311] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:00.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:137:0) after 1m0s
[08:22:00.815] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:137:0)
[08:22:00.815] Timer tick, asking Buildbucket for the build status
[08:22:01.011] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:01.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:138:0) after 1m0s
[08:23:01.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:138:0)
[08:23:01.204] Timer tick, asking Buildbucket for the build status
[08:23:01.496] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:01.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:139:0) after 1m0s
[08:24:01.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:139:0)
[08:24:01.509] Timer tick, asking Buildbucket for the build status
[08:24:01.597] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:01.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:140:0) after 1m0s
[08:25:01.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:140:0)
[08:25:01.713] Timer tick, asking Buildbucket for the build status
[08:25:01.796] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:01.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:141:0) after 1m0s
[08:26:02.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:141:0)
[08:26:02.163] Timer tick, asking Buildbucket for the build status
[08:26:02.565] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:02.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:142:0) after 1m0s
[08:27:02.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:142:0)
[08:27:02.844] Timer tick, asking Buildbucket for the build status
[08:27:03.193] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:03.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:143:0) after 1m0s
[08:28:03.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:143:0)
[08:28:03.249] Timer tick, asking Buildbucket for the build status
[08:28:03.414] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:03.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:144:0) after 1m0s
[08:29:03.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:144:0)
[08:29:03.601] Timer tick, asking Buildbucket for the build status
[08:29:03.691] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:03.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:145:0) after 1m0s
[08:30:03.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:145:0)
[08:30:03.921] Timer tick, asking Buildbucket for the build status
[08:30:04.011] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:04.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:146:0) after 1m0s
[08:31:04.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:146:0)
[08:31:04.164] Timer tick, asking Buildbucket for the build status
[08:31:04.249] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:04.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:147:0) after 1m0s
[08:32:04.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:147:0)
[08:32:04.417] Timer tick, asking Buildbucket for the build status
[08:32:04.514] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:04.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:148:0) after 1m0s
[08:33:04.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:148:0)
[08:33:04.619] Timer tick, asking Buildbucket for the build status
[08:33:04.684] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:04.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:149:0) after 1m0s
[08:34:04.885] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:149:0)
[08:34:04.885] Timer tick, asking Buildbucket for the build status
[08:34:05.073] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:05.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:150:0) after 1m0s
[08:35:05.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:150:0)
[08:35:05.100] Timer tick, asking Buildbucket for the build status
[08:35:05.260] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:05.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:151:0) after 1m0s
[08:36:05.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:151:0)
[08:36:05.367] Timer tick, asking Buildbucket for the build status
[08:36:05.581] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:05.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:152:0) after 1m0s
[08:37:05.710] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:152:0)
[08:37:05.710] Timer tick, asking Buildbucket for the build status
[08:37:06.070] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:06.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:153:0) after 1m0s
[08:38:06.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:153:0)
[08:38:06.418] Timer tick, asking Buildbucket for the build status
[08:38:06.670] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:06.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:154:0) after 1m0s
[08:39:06.878] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:154:0)
[08:39:06.878] Timer tick, asking Buildbucket for the build status
[08:39:06.979] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:06.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:155:0) after 1m0s
[08:40:07.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:155:0)
[08:40:07.401] Timer tick, asking Buildbucket for the build status
[08:40:07.651] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:07.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:156:0) after 1m0s
[08:41:07.679] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:156:0)
[08:41:07.679] Timer tick, asking Buildbucket for the build status
[08:41:07.761] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:07.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:157:0) after 1m0s
[08:42:07.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:157:0)
[08:42:07.870] Timer tick, asking Buildbucket for the build status
[08:42:08.069] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:08.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:158:0) after 1m0s
[08:43:08.200] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:158:0)
[08:43:08.200] Timer tick, asking Buildbucket for the build status
[08:43:08.269] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:08.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:159:0) after 1m0s
[08:44:08.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:159:0)
[08:44:08.287] Timer tick, asking Buildbucket for the build status
[08:44:08.591] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:08.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:160:0) after 1m0s
[08:45:08.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:160:0)
[08:45:08.616] Timer tick, asking Buildbucket for the build status
[08:45:08.832] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:08.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:161:0) after 1m0s
[08:46:08.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:161:0)
[08:46:08.867] Timer tick, asking Buildbucket for the build status
[08:46:09.221] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:09.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:162:0) after 1m0s
[08:47:09.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:162:0)
[08:47:09.307] Timer tick, asking Buildbucket for the build status
[08:47:09.701] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:09.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:163:0) after 1m0s
[08:48:09.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:163:0)
[08:48:09.730] Timer tick, asking Buildbucket for the build status
[08:48:09.865] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:09.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:164:0) after 1m0s
[08:49:09.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:164:0)
[08:49:09.898] Timer tick, asking Buildbucket for the build status
[08:49:09.990] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:09.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:165:0) after 1m0s
[08:50:10.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:165:0)
[08:50:10.045] Timer tick, asking Buildbucket for the build status
[08:50:10.239] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:10.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:166:0) after 1m0s
[08:51:10.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:166:0)
[08:51:10.281] Timer tick, asking Buildbucket for the build status
[08:51:10.607] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:10.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:167:0) after 1m0s
[08:52:10.690] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:167:0)
[08:52:10.690] Timer tick, asking Buildbucket for the build status
[08:52:10.905] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:10.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:168:0) after 1m0s
[08:53:10.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:168:0)
[08:53:10.965] Timer tick, asking Buildbucket for the build status
[08:53:11.089] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:11.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:169:0) after 1m0s
[08:54:11.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:169:0)
[08:54:11.163] Timer tick, asking Buildbucket for the build status
[08:54:11.789] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:11.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:170:0) after 1m0s
[08:55:11.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:170:0)
[08:55:11.836] Timer tick, asking Buildbucket for the build status
[08:55:12.930] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:12.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:171:0) after 1m0s
[08:56:13.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:171:0)
[08:56:13.036] Timer tick, asking Buildbucket for the build status
[08:56:13.104] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:13.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:172:0) after 1m0s
[08:57:13.278] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:172:0)
[08:57:13.278] Timer tick, asking Buildbucket for the build status
[08:57:13.383] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:13.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:173:0) after 1m0s
[08:58:13.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:173:0)
[08:58:13.481] Timer tick, asking Buildbucket for the build status
[08:58:13.542] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:13.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:174:0) after 1m0s
[08:59:13.698] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:174:0)
[08:59:13.698] Timer tick, asking Buildbucket for the build status
[08:59:13.819] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:13.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:175:0) after 1m0s
[09:00:14.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:175:0)
[09:00:14.006] Timer tick, asking Buildbucket for the build status
[09:00:14.193] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:14.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:176:0) after 1m0s
[09:01:14.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:176:0)
[09:01:14.425] Timer tick, asking Buildbucket for the build status
[09:01:14.588] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:14.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:177:0) after 1m0s
[09:02:14.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:177:0)
[09:02:14.756] Timer tick, asking Buildbucket for the build status
[09:02:14.934] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:14.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:178:0) after 1m0s
[09:03:15.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:178:0)
[09:03:15.053] Timer tick, asking Buildbucket for the build status
[09:03:15.113] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:15.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:179:0) after 1m0s
[09:04:15.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:179:0)
[09:04:15.410] Timer tick, asking Buildbucket for the build status
[09:04:15.510] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:15.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:180:0) after 1m0s
[09:05:15.617] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:180:0)
[09:05:15.617] Timer tick, asking Buildbucket for the build status
[09:05:15.702] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:15.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:181:0) after 1m0s
[09:06:15.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:181:0)
[09:06:15.846] Timer tick, asking Buildbucket for the build status
[09:06:15.919] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:15.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:182:0) after 1m0s
[09:07:16.024] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:182:0)
[09:07:16.024] Timer tick, asking Buildbucket for the build status
[09:07:16.297] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:16.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:183:0) after 1m0s
[09:08:17.136] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:183:0)
[09:08:17.136] Timer tick, asking Buildbucket for the build status
[09:08:17.192] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:17.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:184:0) after 1m0s
[09:09:17.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:184:0)
[09:09:17.243] Timer tick, asking Buildbucket for the build status
[09:09:17.323] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:17.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:185:0) after 1m0s
[09:10:17.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:185:0)
[09:10:17.411] Timer tick, asking Buildbucket for the build status
[09:10:17.460] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:17.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:186:0) after 1m0s
[09:11:17.577] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:186:0)
[09:11:17.577] Timer tick, asking Buildbucket for the build status
[09:11:17.720] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:17.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:187:0) after 1m0s
[09:12:17.801] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:187:0)
[09:12:17.801] Timer tick, asking Buildbucket for the build status
[09:12:17.945] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:17.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:188:0) after 1m0s
[09:13:18.020] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:188:0)
[09:13:18.020] Timer tick, asking Buildbucket for the build status
[09:13:18.434] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:18.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:189:0) after 1m0s
[09:14:18.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:189:0)
[09:14:18.488] Timer tick, asking Buildbucket for the build status
[09:14:18.583] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:18.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:190:0) after 1m0s
[09:15:18.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:190:0)
[09:15:18.656] Timer tick, asking Buildbucket for the build status
[09:15:18.725] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:18.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:191:0) after 1m0s
[09:16:18.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:191:0)
[09:16:18.754] Timer tick, asking Buildbucket for the build status
[09:16:18.817] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:18.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:192:0) after 1m0s
[09:17:18.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:192:0)
[09:17:18.846] Timer tick, asking Buildbucket for the build status
[09:17:19.037] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:19.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:193:0) after 1m0s
[09:18:19.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:193:0)
[09:18:19.021] Timer tick, asking Buildbucket for the build status
[09:18:19.366] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:19.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:194:0) after 1m0s
[09:19:19.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:194:0)
[09:19:19.449] Timer tick, asking Buildbucket for the build status
[09:19:19.515] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:19.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:195:0) after 1m0s
[09:20:19.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:195:0)
[09:20:19.563] Timer tick, asking Buildbucket for the build status
[09:20:19.634] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:19.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:196:0) after 1m0s
[09:21:19.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:196:0)
[09:21:19.775] Timer tick, asking Buildbucket for the build status
[09:21:20.080] Build 8858877396945943776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:20.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024307458972948736:197:0) after 1m0s
[09:22:08.720] Received PubSub notification, asking Buildbucket for the build status
[09:22:08.921] Build 8858877396945943776: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:22:08.921] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609924928011963",
"created_by": "project:chromium",
"created_ts": "1609913176548676",
"id": "8858877396945943776",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd13061e37809876a1e2956d59ff6600c30aeef1\"}}",
"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\": \"dd13061e37809876a1e2956d59ff6600c30aeef1\", \"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-8-s0ub\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1319, \"got_angle_revision\": \"b590fd1b9a590909ff184f44c8bf844caecc8d14\", \"got_dawn_revision\": \"a84acc4fcca80d80c379e3b02d8dc0f7b102ba9d\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"dd13061e37809876a1e2956d59ff6600c30aeef1\", \"got_revision_cp\": \"refs/heads/master@{#840496}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"b9e179f1f239bd1da8fffbd997a41095345d1f6a\", \"got_v8_revision\": \"8bf25fa0cda754dd0f8e0f8b7c9f8854eabfe72d\", \"got_v8_revision_cp\": \"refs/heads/8.9.213@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd13061e37809876a1e2956d59ff6600c30aeef1\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f375b1f56d48f8eb1b78ff74f62ac76927591c747ddd46d6194a7cde2679a0e3_v2\", \"git\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-s0ub\"], \"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\": [\"5657-0417a3f\"], \"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": "1609913209618444",
"status": "COMPLETED",
"status_changed_ts": "1609924928318583",
"tags": [
"build_address:luci.chromium.ci/win-official/1319",
"builder:win-official",
"buildset:commit/git/dd13061e37809876a1e2956d59ff6600c30aeef1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd13061e37809876a1e2956d59ff6600c30aeef1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024307458972948736",
"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/8858877396945943776/+/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:50ef1f1d16ebad10",
"user_agent:luci-scheduler"
],
"updated_ts": "1609924928323704",
"url": "https://ci.chromium.org/b/8858877396945943776",
"utcnow_ts": "1609924928901898"
}
}
[09:22:08.922] Invocation finished in 3h15m53.689258734s with status SUCCEEDED