[15:54:42.214] New invocation is queued and will start shortly
[15:54:43.344] Starting the invocation (attempt 1)
[15:54:43.404] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:54:43.404] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:54:43.404] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025719988398828864",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d01e521926019685da40e228ddf7204a8564468f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9025719988398828864",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/d01e521926019685da40e228ddf7204a8564468f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d01e521926019685da40e228ddf7204a8564468f",
"gitiles_ref:refs/heads/master"
]
}
[15:54:44.880] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608566083533015",
"id": "8860289926351598592",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d01e521926019685da40e228ddf7204a8564468f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608566084412594",
"tags": [
"build_address:luci.chromium.ci/win-official/1210",
"builder:win-official",
"buildset:commit/git/d01e521926019685da40e228ddf7204a8564468f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d01e521926019685da40e228ddf7204a8564468f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025719988398828864",
"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/8860289926351598592/+/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": "1608566084412969",
"url": "https://ci.chromium.org/b/8860289926351598592",
"utcnow_ts": "1608566084857732"
}
}
[15:54:44.880] Task URL: https://ci.chromium.org/b/8860289926351598592
[15:54:44.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:2:0) after 1m0s
[15:55:21.631] Received PubSub notification, asking Buildbucket for the build status
[15:55:21.747] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:44.917] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:2:0)
[15:55:44.917] Timer tick, asking Buildbucket for the build status
[15:55:44.996] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:44.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:4:0) after 1m0s
[15:56:45.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:4:0)
[15:56:45.021] Timer tick, asking Buildbucket for the build status
[15:56:45.310] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:45.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:5:0) after 1m0s
[15:57:45.331] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:5:0)
[15:57:45.331] Timer tick, asking Buildbucket for the build status
[15:57:45.457] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:45.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:6:0) after 1m0s
[15:58:45.443] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:6:0)
[15:58:45.443] Timer tick, asking Buildbucket for the build status
[15:58:45.714] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:45.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:7:0) after 1m0s
[15:59:45.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:7:0)
[15:59:45.768] Timer tick, asking Buildbucket for the build status
[15:59:45.858] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:45.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:8:0) after 1m0s
[16:00:45.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:8:0)
[16:00:45.952] Timer tick, asking Buildbucket for the build status
[16:00:46.803] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:46.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:9:0) after 1m0s
[16:01:46.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:9:0)
[16:01:46.919] Timer tick, asking Buildbucket for the build status
[16:01:47.264] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:47.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:10:0) after 1m0s
[16:02:47.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:10:0)
[16:02:47.304] Timer tick, asking Buildbucket for the build status
[16:02:47.420] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:47.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:11:0) after 1m0s
[16:03:47.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:11:0)
[16:03:47.481] Timer tick, asking Buildbucket for the build status
[16:03:47.653] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:47.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:12:0) after 1m0s
[16:04:47.749] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:12:0)
[16:04:47.749] Timer tick, asking Buildbucket for the build status
[16:04:48.029] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:48.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:13:0) after 1m0s
[16:05:48.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:13:0)
[16:05:48.083] Timer tick, asking Buildbucket for the build status
[16:05:48.167] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:48.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:14:0) after 1m0s
[16:06:48.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:14:0)
[16:06:48.307] Timer tick, asking Buildbucket for the build status
[16:06:48.406] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:48.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:15:0) after 1m0s
[16:07:48.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:15:0)
[16:07:48.553] Timer tick, asking Buildbucket for the build status
[16:07:48.657] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:48.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:16:0) after 1m0s
[16:08:48.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:16:0)
[16:08:48.808] Timer tick, asking Buildbucket for the build status
[16:08:48.880] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:48.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:17:0) after 1m0s
[16:09:49.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:17:0)
[16:09:49.140] Timer tick, asking Buildbucket for the build status
[16:09:49.314] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:49.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:18:0) after 1m0s
[16:10:49.480] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:18:0)
[16:10:49.480] Timer tick, asking Buildbucket for the build status
[16:10:49.537] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:49.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:19:0) after 1m0s
[16:11:49.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:19:0)
[16:11:49.637] Timer tick, asking Buildbucket for the build status
[16:11:49.738] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:49.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:20:0) after 1m0s
[16:12:49.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:20:0)
[16:12:49.799] Timer tick, asking Buildbucket for the build status
[16:12:49.873] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:49.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:21:0) after 1m0s
[16:13:50.084] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:21:0)
[16:13:50.084] Timer tick, asking Buildbucket for the build status
[16:13:50.206] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:50.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:22:0) after 1m0s
[16:14:50.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:22:0)
[16:14:50.235] Timer tick, asking Buildbucket for the build status
[16:14:50.343] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:50.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:23:0) after 1m0s
[16:15:50.374] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:23:0)
[16:15:50.374] Timer tick, asking Buildbucket for the build status
[16:15:50.458] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:50.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:24:0) after 1m0s
[16:16:50.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:24:0)
[16:16:50.526] Timer tick, asking Buildbucket for the build status
[16:16:50.622] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:50.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:25:0) after 1m0s
[16:17:50.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:25:0)
[16:17:50.662] Timer tick, asking Buildbucket for the build status
[16:17:50.711] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:50.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:26:0) after 1m0s
[16:18:50.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:26:0)
[16:18:50.736] Timer tick, asking Buildbucket for the build status
[16:18:50.824] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:50.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:27:0) after 1m0s
[16:19:50.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:27:0)
[16:19:50.879] Timer tick, asking Buildbucket for the build status
[16:19:50.988] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:50.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:28:0) after 1m0s
[16:20:51.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:28:0)
[16:20:51.000] Timer tick, asking Buildbucket for the build status
[16:20:51.293] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:51.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:29:0) after 1m0s
[16:21:51.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:29:0)
[16:21:51.349] Timer tick, asking Buildbucket for the build status
[16:21:51.447] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:51.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:30:0) after 1m0s
[16:22:51.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:30:0)
[16:22:51.539] Timer tick, asking Buildbucket for the build status
[16:22:51.593] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:51.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:31:0) after 1m0s
[16:23:51.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:31:0)
[16:23:51.603] Timer tick, asking Buildbucket for the build status
[16:23:51.668] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:51.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:32:0) after 1m0s
[16:24:51.693] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:32:0)
[16:24:51.693] Timer tick, asking Buildbucket for the build status
[16:24:51.869] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:51.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:33:0) after 1m0s
[16:25:51.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:33:0)
[16:25:51.872] Timer tick, asking Buildbucket for the build status
[16:25:51.941] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:51.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:34:0) after 1m0s
[16:26:51.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:34:0)
[16:26:51.962] Timer tick, asking Buildbucket for the build status
[16:26:52.031] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:52.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:35:0) after 1m0s
[16:27:52.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:35:0)
[16:27:52.062] Timer tick, asking Buildbucket for the build status
[16:27:52.130] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:52.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:36:0) after 1m0s
[16:28:52.154] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:36:0)
[16:28:52.154] Timer tick, asking Buildbucket for the build status
[16:28:52.331] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:52.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:37:0) after 1m0s
[16:29:52.377] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:37:0)
[16:29:52.377] Timer tick, asking Buildbucket for the build status
[16:29:52.482] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:52.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:38:0) after 1m0s
[16:30:52.550] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:38:0)
[16:30:52.552] Timer tick, asking Buildbucket for the build status
[16:30:52.655] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:52.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:39:0) after 1m0s
[16:31:52.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:39:0)
[16:31:52.685] Timer tick, asking Buildbucket for the build status
[16:31:52.781] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:52.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:40:0) after 1m0s
[16:32:52.813] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:40:0)
[16:32:52.813] Timer tick, asking Buildbucket for the build status
[16:32:52.868] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:52.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:41:0) after 1m0s
[16:33:52.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:41:0)
[16:33:52.909] Timer tick, asking Buildbucket for the build status
[16:33:53.092] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:53.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:42:0) after 1m0s
[16:34:53.114] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:42:0)
[16:34:53.114] Timer tick, asking Buildbucket for the build status
[16:34:53.235] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:53.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:43:0) after 1m0s
[16:35:53.294] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:43:0)
[16:35:53.294] Timer tick, asking Buildbucket for the build status
[16:35:53.346] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:53.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:44:0) after 1m0s
[16:36:53.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:44:0)
[16:36:53.415] Timer tick, asking Buildbucket for the build status
[16:36:53.468] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:53.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:45:0) after 1m0s
[16:37:53.494] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:45:0)
[16:37:53.494] Timer tick, asking Buildbucket for the build status
[16:37:53.551] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:53.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:46:0) after 1m0s
[16:38:53.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:46:0)
[16:38:53.567] Timer tick, asking Buildbucket for the build status
[16:38:53.643] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:53.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:47:0) after 1m0s
[16:39:53.740] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:47:0)
[16:39:53.740] Timer tick, asking Buildbucket for the build status
[16:39:53.839] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:53.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:48:0) after 1m0s
[16:40:53.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:48:0)
[16:40:53.871] Timer tick, asking Buildbucket for the build status
[16:40:53.975] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:53.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:49:0) after 1m0s
[16:41:54.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:49:0)
[16:41:54.005] Timer tick, asking Buildbucket for the build status
[16:41:54.068] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:54.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:50:0) after 1m0s
[16:42:54.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:50:0)
[16:42:54.158] Timer tick, asking Buildbucket for the build status
[16:42:54.218] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:54.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:51:0) after 1m0s
[16:43:54.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:51:0)
[16:43:54.283] Timer tick, asking Buildbucket for the build status
[16:43:54.370] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:54.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:52:0) after 1m0s
[16:44:54.462] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:52:0)
[16:44:54.462] Timer tick, asking Buildbucket for the build status
[16:44:54.752] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:54.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:53:0) after 1m0s
[16:45:54.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:53:0)
[16:45:54.756] Timer tick, asking Buildbucket for the build status
[16:45:54.906] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:54.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:54:0) after 1m0s
[16:46:55.024] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:54:0)
[16:46:55.024] Timer tick, asking Buildbucket for the build status
[16:46:55.082] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:55.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:55:0) after 1m0s
[16:47:55.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:55:0)
[16:47:55.117] Timer tick, asking Buildbucket for the build status
[16:47:55.173] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:55.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:56:0) after 1m0s
[16:48:55.251] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:56:0)
[16:48:55.251] Timer tick, asking Buildbucket for the build status
[16:48:55.314] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:55.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:57:0) after 1m0s
[16:49:55.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:57:0)
[16:49:55.437] Timer tick, asking Buildbucket for the build status
[16:49:55.531] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:55.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:58:0) after 1m0s
[16:50:55.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:58:0)
[16:50:55.553] Timer tick, asking Buildbucket for the build status
[16:50:55.611] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:55.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:59:0) after 1m0s
[16:51:55.670] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:59:0)
[16:51:55.670] Timer tick, asking Buildbucket for the build status
[16:51:55.728] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:55.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:60:0) after 1m0s
[16:52:55.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:60:0)
[16:52:55.780] Timer tick, asking Buildbucket for the build status
[16:52:55.932] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:55.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:61:0) after 1m0s
[16:53:56.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:61:0)
[16:53:56.005] Timer tick, asking Buildbucket for the build status
[16:53:56.049] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:56.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:62:0) after 1m0s
[16:54:56.103] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:62:0)
[16:54:56.103] Timer tick, asking Buildbucket for the build status
[16:54:56.691] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:56.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:63:0) after 1m0s
[16:55:56.762] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:63:0)
[16:55:56.762] Timer tick, asking Buildbucket for the build status
[16:55:56.825] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:56.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:64:0) after 1m0s
[16:56:56.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:64:0)
[16:56:56.881] Timer tick, asking Buildbucket for the build status
[16:56:57.176] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:57.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:65:0) after 1m0s
[16:57:57.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:65:0)
[16:57:57.266] Timer tick, asking Buildbucket for the build status
[16:57:57.329] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:57.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:66:0) after 1m0s
[16:58:57.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:66:0)
[16:58:57.384] Timer tick, asking Buildbucket for the build status
[16:58:57.458] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:57.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:67:0) after 1m0s
[16:59:57.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:67:0)
[16:59:57.501] Timer tick, asking Buildbucket for the build status
[16:59:57.722] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:57.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:68:0) after 1m0s
[17:00:57.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:68:0)
[17:00:57.836] Timer tick, asking Buildbucket for the build status
[17:00:57.981] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:57.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:69:0) after 1m0s
[17:01:58.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:69:0)
[17:01:58.013] Timer tick, asking Buildbucket for the build status
[17:01:58.087] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:58.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:70:0) after 1m0s
[17:02:58.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:70:0)
[17:02:58.178] Timer tick, asking Buildbucket for the build status
[17:02:58.249] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:58.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:71:0) after 1m0s
[17:03:58.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:71:0)
[17:03:58.236] Timer tick, asking Buildbucket for the build status
[17:03:58.385] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:58.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:72:0) after 1m0s
[17:04:58.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:72:0)
[17:04:58.442] Timer tick, asking Buildbucket for the build status
[17:04:58.531] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:58.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:73:0) after 1m0s
[17:05:58.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:73:0)
[17:05:58.580] Timer tick, asking Buildbucket for the build status
[17:05:58.648] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:58.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:74:0) after 1m0s
[17:06:58.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:74:0)
[17:06:58.639] Timer tick, asking Buildbucket for the build status
[17:06:59.630] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:59.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:75:0) after 1m0s
[17:07:59.659] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:75:0)
[17:07:59.659] Timer tick, asking Buildbucket for the build status
[17:07:59.733] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:59.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:76:0) after 1m0s
[17:08:59.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:76:0)
[17:08:59.757] Timer tick, asking Buildbucket for the build status
[17:08:59.848] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:59.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:77:0) after 1m0s
[17:09:59.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:77:0)
[17:09:59.867] Timer tick, asking Buildbucket for the build status
[17:10:00.221] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:00.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:78:0) after 1m0s
[17:11:01.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:78:0)
[17:11:01.389] Timer tick, asking Buildbucket for the build status
[17:11:01.470] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:01.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:79:0) after 1m0s
[17:12:01.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:79:0)
[17:12:01.927] Timer tick, asking Buildbucket for the build status
[17:12:01.989] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:01.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:80:0) after 1m0s
[17:13:02.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:80:0)
[17:13:02.127] Timer tick, asking Buildbucket for the build status
[17:13:02.212] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:02.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:81:0) after 1m0s
[17:14:02.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:81:0)
[17:14:02.350] Timer tick, asking Buildbucket for the build status
[17:14:02.408] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:02.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:82:0) after 1m0s
[17:15:02.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:82:0)
[17:15:02.404] Timer tick, asking Buildbucket for the build status
[17:15:02.532] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:02.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:83:0) after 1m0s
[17:16:02.557] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:83:0)
[17:16:02.557] Timer tick, asking Buildbucket for the build status
[17:16:02.717] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:02.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:84:0) after 1m0s
[17:17:02.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:84:0)
[17:17:02.982] Timer tick, asking Buildbucket for the build status
[17:17:03.092] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:03.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:85:0) after 1m0s
[17:18:03.333] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:85:0)
[17:18:03.333] Timer tick, asking Buildbucket for the build status
[17:18:03.474] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:03.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:86:0) after 1m0s
[17:19:03.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:86:0)
[17:19:03.526] Timer tick, asking Buildbucket for the build status
[17:19:03.579] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:03.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:87:0) after 1m0s
[17:20:03.681] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:87:0)
[17:20:03.681] Timer tick, asking Buildbucket for the build status
[17:20:03.799] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:03.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:88:0) after 1m0s
[17:21:03.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:88:0)
[17:21:03.888] Timer tick, asking Buildbucket for the build status
[17:21:03.946] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:03.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:89:0) after 1m0s
[17:22:04.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:89:0)
[17:22:04.015] Timer tick, asking Buildbucket for the build status
[17:22:04.262] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:04.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:90:0) after 1m0s
[17:23:04.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:90:0)
[17:23:04.481] Timer tick, asking Buildbucket for the build status
[17:23:04.748] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:04.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:91:0) after 1m0s
[17:24:05.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:91:0)
[17:24:05.275] Timer tick, asking Buildbucket for the build status
[17:24:05.360] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:05.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:92:0) after 1m0s
[17:25:06.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:92:0)
[17:25:06.065] Timer tick, asking Buildbucket for the build status
[17:25:06.333] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:06.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:93:0) after 1m0s
[17:26:07.541] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:93:0)
[17:26:07.541] Timer tick, asking Buildbucket for the build status
[17:26:07.679] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:07.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:94:0) after 1m0s
[17:27:08.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:94:0)
[17:27:08.052] Timer tick, asking Buildbucket for the build status
[17:27:08.179] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:08.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:95:0) after 1m0s
[17:28:08.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:95:0)
[17:28:08.204] Timer tick, asking Buildbucket for the build status
[17:28:08.282] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:08.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:96:0) after 1m0s
[17:29:08.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:96:0)
[17:29:08.533] Timer tick, asking Buildbucket for the build status
[17:29:08.590] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:08.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:97:0) after 1m0s
[17:30:08.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:97:0)
[17:30:08.653] Timer tick, asking Buildbucket for the build status
[17:30:08.765] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:08.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:98:0) after 1m0s
[17:31:09.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:98:0)
[17:31:09.565] Timer tick, asking Buildbucket for the build status
[17:31:09.746] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:09.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:99:0) after 1m0s
[17:32:09.815] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:99:0)
[17:32:09.815] Timer tick, asking Buildbucket for the build status
[17:32:09.878] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:09.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:100:0) after 1m0s
[17:33:10.001] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:100:0)
[17:33:10.001] Timer tick, asking Buildbucket for the build status
[17:33:10.136] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:10.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:101:0) after 1m0s
[17:34:10.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:101:0)
[17:34:10.779] Timer tick, asking Buildbucket for the build status
[17:34:12.008] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:12.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:102:0) after 1m0s
[17:35:12.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:102:0)
[17:35:12.185] Timer tick, asking Buildbucket for the build status
[17:35:12.289] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:12.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:103:0) after 1m0s
[17:36:12.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:103:0)
[17:36:12.579] Timer tick, asking Buildbucket for the build status
[17:36:12.655] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:12.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:104:0) after 1m0s
[17:37:12.812] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:104:0)
[17:37:12.812] Timer tick, asking Buildbucket for the build status
[17:37:12.873] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:12.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:105:0) after 1m0s
[17:38:13.001] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:105:0)
[17:38:13.001] Timer tick, asking Buildbucket for the build status
[17:38:13.060] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:13.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:106:0) after 1m0s
[17:39:13.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:106:0)
[17:39:13.687] Timer tick, asking Buildbucket for the build status
[17:39:13.761] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:13.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:107:0) after 1m0s
[17:40:14.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:107:0)
[17:40:14.199] Timer tick, asking Buildbucket for the build status
[17:40:14.315] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:14.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:108:0) after 1m0s
[17:41:14.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:108:0)
[17:41:14.729] Timer tick, asking Buildbucket for the build status
[17:41:14.824] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:14.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:109:0) after 1m0s
[17:42:14.875] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:109:0)
[17:42:14.875] Timer tick, asking Buildbucket for the build status
[17:42:14.989] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:14.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:110:0) after 1m0s
[17:43:14.980] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:110:0)
[17:43:14.980] Timer tick, asking Buildbucket for the build status
[17:43:15.037] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:15.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:111:0) after 1m0s
[17:44:15.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:111:0)
[17:44:15.193] Timer tick, asking Buildbucket for the build status
[17:44:15.437] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:15.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:112:0) after 1m0s
[17:45:15.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:112:0)
[17:45:15.514] Timer tick, asking Buildbucket for the build status
[17:45:16.130] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:16.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:113:0) after 1m0s
[17:46:16.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:113:0)
[17:46:16.159] Timer tick, asking Buildbucket for the build status
[17:46:16.238] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:16.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:114:0) after 1m0s
[17:47:16.385] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:114:0)
[17:47:16.385] Timer tick, asking Buildbucket for the build status
[17:47:16.660] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:16.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:115:0) after 1m0s
[17:48:16.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:115:0)
[17:48:16.642] Timer tick, asking Buildbucket for the build status
[17:48:16.811] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:16.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:116:0) after 1m0s
[17:49:16.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:116:0)
[17:49:16.899] Timer tick, asking Buildbucket for the build status
[17:49:17.188] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:17.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:117:0) after 1m0s
[17:50:17.376] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:117:0)
[17:50:17.376] Timer tick, asking Buildbucket for the build status
[17:50:17.796] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:17.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:118:0) after 1m0s
[17:51:17.824] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:118:0)
[17:51:17.824] Timer tick, asking Buildbucket for the build status
[17:51:18.161] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:18.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:119:0) after 1m0s
[17:52:18.643] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:119:0)
[17:52:18.643] Timer tick, asking Buildbucket for the build status
[17:52:18.770] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:18.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:120:0) after 1m0s
[17:53:19.129] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:120:0)
[17:53:19.129] Timer tick, asking Buildbucket for the build status
[17:53:19.180] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:19.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:121:0) after 1m0s
[17:54:19.346] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:121:0)
[17:54:19.346] Timer tick, asking Buildbucket for the build status
[17:54:19.569] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:19.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:122:0) after 1m0s
[17:55:19.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:122:0)
[17:55:19.930] Timer tick, asking Buildbucket for the build status
[17:55:20.044] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:20.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:123:0) after 1m0s
[17:56:20.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:123:0)
[17:56:20.465] Timer tick, asking Buildbucket for the build status
[17:56:20.533] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:20.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:124:0) after 1m0s
[17:57:20.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:124:0)
[17:57:20.639] Timer tick, asking Buildbucket for the build status
[17:57:20.700] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:20.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:125:0) after 1m0s
[17:58:20.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:125:0)
[17:58:20.729] Timer tick, asking Buildbucket for the build status
[17:58:20.820] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:20.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:126:0) after 1m0s
[17:59:21.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:126:0)
[17:59:21.131] Timer tick, asking Buildbucket for the build status
[17:59:21.214] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:21.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:127:0) after 1m0s
[18:00:21.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:127:0)
[18:00:21.406] Timer tick, asking Buildbucket for the build status
[18:00:21.543] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:21.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:128:0) after 1m0s
[18:01:21.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:128:0)
[18:01:21.646] Timer tick, asking Buildbucket for the build status
[18:01:21.754] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:21.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:129:0) after 1m0s
[18:02:21.875] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:129:0)
[18:02:21.875] Timer tick, asking Buildbucket for the build status
[18:02:21.992] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:21.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:130:0) after 1m0s
[18:03:22.081] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:130:0)
[18:03:22.081] Timer tick, asking Buildbucket for the build status
[18:03:22.182] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:22.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:131:0) after 1m0s
[18:04:22.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:131:0)
[18:04:22.260] Timer tick, asking Buildbucket for the build status
[18:04:22.523] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:22.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:132:0) after 1m0s
[18:05:22.714] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:132:0)
[18:05:22.714] Timer tick, asking Buildbucket for the build status
[18:05:22.819] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:22.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:133:0) after 1m0s
[18:06:23.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:133:0)
[18:06:23.259] Timer tick, asking Buildbucket for the build status
[18:06:23.309] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:23.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:134:0) after 1m0s
[18:07:23.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:134:0)
[18:07:23.602] Timer tick, asking Buildbucket for the build status
[18:07:23.696] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:23.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:135:0) after 1m0s
[18:08:23.815] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:135:0)
[18:08:23.815] Timer tick, asking Buildbucket for the build status
[18:08:24.008] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:24.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:136:0) after 1m0s
[18:09:24.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:136:0)
[18:09:24.123] Timer tick, asking Buildbucket for the build status
[18:09:24.209] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:24.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:137:0) after 1m0s
[18:10:24.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:137:0)
[18:10:24.271] Timer tick, asking Buildbucket for the build status
[18:10:24.388] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:24.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:138:0) after 1m0s
[18:11:24.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:138:0)
[18:11:24.572] Timer tick, asking Buildbucket for the build status
[18:11:24.621] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:24.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:139:0) after 1m0s
[18:12:24.705] Handling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:139:0)
[18:12:24.705] Timer tick, asking Buildbucket for the build status
[18:12:24.781] Build 8860289926351598592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:24.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9025719988398828864:140:0) after 1m0s
[18:12:30.790] Received PubSub notification, asking Buildbucket for the build status
[18:12:30.969] Build 8860289926351598592: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:12:30.969] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608574349750006",
"created_by": "project:chromium",
"created_ts": "1608566083533015",
"id": "8860289926351598592",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d01e521926019685da40e228ddf7204a8564468f\"}}",
"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\": \"d01e521926019685da40e228ddf7204a8564468f\", \"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-1-pt05\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1210, \"got_angle_revision\": \"4ebcee3eb02ffdd586e20965b9c4bb609876db36\", \"got_dawn_revision\": \"5d4fd88a7403aa255d49919d011af54c691b344a\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"d01e521926019685da40e228ddf7204a8564468f\", \"got_revision_cp\": \"refs/heads/master@{#838652}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"b96d7e2a4be85264b9cd7ba26d91f7fb98baa9a5\", \"got_v8_revision_cp\": \"refs/heads/8.9.179@{#1}\", \"got_webrtc_revision\": \"ef9c4e07a351c9396c72e72efaa1be3df728c440\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32863}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d01e521926019685da40e228ddf7204a8564468f\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-1-pt05\"], \"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": "1608566120992550",
"status": "COMPLETED",
"status_changed_ts": "1608574350284388",
"tags": [
"build_address:luci.chromium.ci/win-official/1210",
"builder:win-official",
"buildset:commit/git/d01e521926019685da40e228ddf7204a8564468f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d01e521926019685da40e228ddf7204a8564468f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025719988398828864",
"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/8860289926351598592/+/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:509ed41d85134f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608574350353461",
"url": "https://ci.chromium.org/b/8860289926351598592",
"utcnow_ts": "1608574350958380"
}
}
[18:12:30.969] Invocation finished in 2h17m48.771891479s with status SUCCEEDED