[17:38:48.569] New invocation is queued and will start shortly
[17:38:49.645] Starting the invocation (attempt 1)
[17:38:49.684] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:38:49.684] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:38:49.684] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024173290192666224",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7f22ba8212c4cbe91f8c441376ca48f1de672d57\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9024173290192666224",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/7f22ba8212c4cbe91f8c441376ca48f1de672d57",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f22ba8212c4cbe91f8c441376ca48f1de672d57",
"gitiles_ref:refs/heads/master"
]
}
[17:38:50.744] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610041129865463",
"id": "8858743228168439872",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7f22ba8212c4cbe91f8c441376ca48f1de672d57\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610041130643183",
"tags": [
"build_address:luci.chromium.ci/win-official/1330",
"builder:win-official",
"buildset:commit/git/7f22ba8212c4cbe91f8c441376ca48f1de672d57",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f22ba8212c4cbe91f8c441376ca48f1de672d57",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024173290192666224",
"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/8858743228168439872/+/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": "1610041130643366",
"url": "https://ci.chromium.org/b/8858743228168439872",
"utcnow_ts": "1610041130737497"
}
}
[17:38:50.744] Task URL: https://ci.chromium.org/b/8858743228168439872
[17:38:50.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:2:0) after 1m0s
[17:39:33.233] Received PubSub notification, asking Buildbucket for the build status
[17:39:33.291] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:50.815] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:2:0)
[17:39:50.815] Timer tick, asking Buildbucket for the build status
[17:39:50.875] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:50.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:4:0) after 1m0s
[17:40:51.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:4:0)
[17:40:51.033] Timer tick, asking Buildbucket for the build status
[17:40:51.132] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:51.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:5:0) after 1m0s
[17:41:51.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:5:0)
[17:41:51.155] Timer tick, asking Buildbucket for the build status
[17:41:51.243] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:51.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:6:0) after 1m0s
[17:42:51.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:6:0)
[17:42:51.318] Timer tick, asking Buildbucket for the build status
[17:42:51.450] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:51.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:7:0) after 1m0s
[17:43:51.476] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:7:0)
[17:43:51.476] Timer tick, asking Buildbucket for the build status
[17:43:51.565] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:51.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:8:0) after 1m0s
[17:44:51.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:8:0)
[17:44:51.579] Timer tick, asking Buildbucket for the build status
[17:44:51.737] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:51.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:9:0) after 1m0s
[17:45:51.852] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:9:0)
[17:45:51.852] Timer tick, asking Buildbucket for the build status
[17:45:51.946] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:51.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:10:0) after 1m0s
[17:46:52.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:10:0)
[17:46:52.006] Timer tick, asking Buildbucket for the build status
[17:46:52.083] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:52.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:11:0) after 1m0s
[17:47:52.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:11:0)
[17:47:52.282] Timer tick, asking Buildbucket for the build status
[17:47:52.354] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:52.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:12:0) after 1m0s
[17:48:52.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:12:0)
[17:48:52.409] Timer tick, asking Buildbucket for the build status
[17:48:52.594] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:52.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:13:0) after 1m0s
[17:49:53.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:13:0)
[17:49:53.077] Timer tick, asking Buildbucket for the build status
[17:49:53.464] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:53.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:14:0) after 1m0s
[17:50:53.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:14:0)
[17:50:53.552] Timer tick, asking Buildbucket for the build status
[17:50:53.743] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:53.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:15:0) after 1m0s
[17:51:53.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:15:0)
[17:51:53.805] Timer tick, asking Buildbucket for the build status
[17:51:54.208] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:54.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:16:0) after 1m0s
[17:52:54.248] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:16:0)
[17:52:54.248] Timer tick, asking Buildbucket for the build status
[17:52:54.352] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:54.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:17:0) after 1m0s
[17:53:54.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:17:0)
[17:53:54.349] Timer tick, asking Buildbucket for the build status
[17:53:54.633] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:54.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:18:0) after 1m0s
[17:54:54.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:18:0)
[17:54:54.734] Timer tick, asking Buildbucket for the build status
[17:54:54.825] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:54.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:19:0) after 1m0s
[17:55:54.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:19:0)
[17:55:54.825] Timer tick, asking Buildbucket for the build status
[17:55:54.877] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:54.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:20:0) after 1m0s
[17:56:54.946] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:20:0)
[17:56:54.946] Timer tick, asking Buildbucket for the build status
[17:56:55.015] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:55.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:21:0) after 1m0s
[17:57:55.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:21:0)
[17:57:55.125] Timer tick, asking Buildbucket for the build status
[17:57:55.315] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:55.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:22:0) after 1m0s
[17:58:55.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:22:0)
[17:58:55.342] Timer tick, asking Buildbucket for the build status
[17:58:55.471] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:55.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:23:0) after 1m0s
[17:59:55.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:23:0)
[17:59:55.695] Timer tick, asking Buildbucket for the build status
[17:59:55.755] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:55.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:24:0) after 1m0s
[18:00:55.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:24:0)
[18:00:55.811] Timer tick, asking Buildbucket for the build status
[18:00:55.930] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:55.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:25:0) after 1m0s
[18:01:56.061] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:25:0)
[18:01:56.061] Timer tick, asking Buildbucket for the build status
[18:01:56.243] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:56.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:26:0) after 1m0s
[18:02:56.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:26:0)
[18:02:56.313] Timer tick, asking Buildbucket for the build status
[18:02:56.777] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:56.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:27:0) after 1m0s
[18:03:56.826] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:27:0)
[18:03:56.826] Timer tick, asking Buildbucket for the build status
[18:03:57.307] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:57.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:28:0) after 1m0s
[18:04:57.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:28:0)
[18:04:57.422] Timer tick, asking Buildbucket for the build status
[18:04:57.518] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:57.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:29:0) after 1m0s
[18:05:57.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:29:0)
[18:05:57.534] Timer tick, asking Buildbucket for the build status
[18:05:57.609] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:57.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:30:0) after 1m0s
[18:06:57.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:30:0)
[18:06:57.640] Timer tick, asking Buildbucket for the build status
[18:06:57.706] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:57.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:31:0) after 1m0s
[18:07:57.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:31:0)
[18:07:57.765] Timer tick, asking Buildbucket for the build status
[18:07:57.880] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:57.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:32:0) after 1m0s
[18:08:57.901] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:32:0)
[18:08:57.901] Timer tick, asking Buildbucket for the build status
[18:08:58.014] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:58.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:33:0) after 1m0s
[18:09:58.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:33:0)
[18:09:58.038] Timer tick, asking Buildbucket for the build status
[18:09:58.102] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:58.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:34:0) after 1m0s
[18:10:58.154] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:34:0)
[18:10:58.154] Timer tick, asking Buildbucket for the build status
[18:10:58.233] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:58.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:35:0) after 1m0s
[18:11:58.251] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:35:0)
[18:11:58.251] Timer tick, asking Buildbucket for the build status
[18:11:58.562] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:58.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:36:0) after 1m0s
[18:12:58.564] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:36:0)
[18:12:58.564] Timer tick, asking Buildbucket for the build status
[18:12:58.628] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:58.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:37:0) after 1m0s
[18:13:58.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:37:0)
[18:13:58.666] Timer tick, asking Buildbucket for the build status
[18:13:58.806] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:58.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:38:0) after 1m0s
[18:14:58.841] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:38:0)
[18:14:58.841] Timer tick, asking Buildbucket for the build status
[18:14:59.322] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:59.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:39:0) after 1m0s
[18:15:59.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:39:0)
[18:15:59.404] Timer tick, asking Buildbucket for the build status
[18:15:59.564] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:59.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:40:0) after 1m0s
[18:16:59.549] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:40:0)
[18:16:59.549] Timer tick, asking Buildbucket for the build status
[18:16:59.712] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:59.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:41:0) after 1m0s
[18:17:59.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:41:0)
[18:17:59.750] Timer tick, asking Buildbucket for the build status
[18:17:59.851] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:59.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:42:0) after 1m0s
[18:18:59.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:42:0)
[18:18:59.876] Timer tick, asking Buildbucket for the build status
[18:19:00.195] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:00.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:43:0) after 1m0s
[18:20:00.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:43:0)
[18:20:00.189] Timer tick, asking Buildbucket for the build status
[18:20:00.267] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:00.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:44:0) after 1m0s
[18:21:00.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:44:0)
[18:21:00.302] Timer tick, asking Buildbucket for the build status
[18:21:00.374] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:00.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:45:0) after 1m0s
[18:22:00.360] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:45:0)
[18:22:00.360] Timer tick, asking Buildbucket for the build status
[18:22:00.430] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:00.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:46:0) after 1m0s
[18:23:01.321] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:46:0)
[18:23:01.321] Timer tick, asking Buildbucket for the build status
[18:23:01.385] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:01.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:47:0) after 1m0s
[18:24:01.420] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:47:0)
[18:24:01.420] Timer tick, asking Buildbucket for the build status
[18:24:01.681] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:01.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:48:0) after 1m0s
[18:25:01.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:48:0)
[18:25:01.876] Timer tick, asking Buildbucket for the build status
[18:25:01.955] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:01.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:49:0) after 1m0s
[18:26:01.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:49:0)
[18:26:01.999] Timer tick, asking Buildbucket for the build status
[18:26:02.109] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:02.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:50:0) after 1m0s
[18:27:02.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:50:0)
[18:27:02.687] Timer tick, asking Buildbucket for the build status
[18:27:02.847] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:02.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:51:0) after 1m0s
[18:28:02.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:51:0)
[18:28:02.918] Timer tick, asking Buildbucket for the build status
[18:28:02.982] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:02.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:52:0) after 1m0s
[18:29:03.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:52:0)
[18:29:03.075] Timer tick, asking Buildbucket for the build status
[18:29:03.482] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:03.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:53:0) after 1m0s
[18:30:03.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:53:0)
[18:30:03.551] Timer tick, asking Buildbucket for the build status
[18:30:03.621] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:03.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:54:0) after 1m0s
[18:31:03.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:54:0)
[18:31:03.677] Timer tick, asking Buildbucket for the build status
[18:31:03.775] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:03.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:55:0) after 1m0s
[18:32:03.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:55:0)
[18:32:03.880] Timer tick, asking Buildbucket for the build status
[18:32:04.032] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:04.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:56:0) after 1m0s
[18:33:04.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:56:0)
[18:33:04.058] Timer tick, asking Buildbucket for the build status
[18:33:04.396] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:04.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:57:0) after 1m0s
[18:34:04.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:57:0)
[18:34:04.431] Timer tick, asking Buildbucket for the build status
[18:34:04.808] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:04.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:58:0) after 1m0s
[18:35:04.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:58:0)
[18:35:04.894] Timer tick, asking Buildbucket for the build status
[18:35:05.080] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:05.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:59:0) after 1m0s
[18:36:05.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:59:0)
[18:36:05.113] Timer tick, asking Buildbucket for the build status
[18:36:05.281] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:05.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:60:0) after 1m0s
[18:37:05.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:60:0)
[18:37:05.281] Timer tick, asking Buildbucket for the build status
[18:37:05.376] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:05.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:61:0) after 1m0s
[18:38:05.479] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:61:0)
[18:38:05.479] Timer tick, asking Buildbucket for the build status
[18:38:06.075] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:06.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:62:0) after 1m0s
[18:39:06.242] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:62:0)
[18:39:06.242] Timer tick, asking Buildbucket for the build status
[18:39:06.506] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:06.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:63:0) after 1m0s
[18:40:06.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:63:0)
[18:40:06.573] Timer tick, asking Buildbucket for the build status
[18:40:07.242] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:07.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:64:0) after 1m0s
[18:41:07.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:64:0)
[18:41:07.225] Timer tick, asking Buildbucket for the build status
[18:41:07.292] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:07.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:65:0) after 1m0s
[18:42:07.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:65:0)
[18:42:07.393] Timer tick, asking Buildbucket for the build status
[18:42:07.487] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:07.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:66:0) after 1m0s
[18:43:07.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:66:0)
[18:43:07.614] Timer tick, asking Buildbucket for the build status
[18:43:07.706] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:07.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:67:0) after 1m0s
[18:44:07.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:67:0)
[18:44:07.786] Timer tick, asking Buildbucket for the build status
[18:44:07.936] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:07.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:68:0) after 1m0s
[18:45:07.961] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:68:0)
[18:45:07.961] Timer tick, asking Buildbucket for the build status
[18:45:08.134] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:08.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:69:0) after 1m0s
[18:46:08.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:69:0)
[18:46:08.183] Timer tick, asking Buildbucket for the build status
[18:46:08.569] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:08.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:70:0) after 1m0s
[18:47:08.634] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:70:0)
[18:47:08.634] Timer tick, asking Buildbucket for the build status
[18:47:08.765] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:08.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:71:0) after 1m0s
[18:48:08.875] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:71:0)
[18:48:08.875] Timer tick, asking Buildbucket for the build status
[18:48:09.015] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:09.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:72:0) after 1m0s
[18:49:09.129] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:72:0)
[18:49:09.129] Timer tick, asking Buildbucket for the build status
[18:49:09.284] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:09.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:73:0) after 1m0s
[18:50:09.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:73:0)
[18:50:09.409] Timer tick, asking Buildbucket for the build status
[18:50:09.553] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:09.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:74:0) after 1m0s
[18:51:09.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:74:0)
[18:51:09.729] Timer tick, asking Buildbucket for the build status
[18:51:09.930] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:09.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:75:0) after 1m0s
[18:52:10.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:75:0)
[18:52:10.043] Timer tick, asking Buildbucket for the build status
[18:52:10.123] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:10.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:76:0) after 1m0s
[18:53:10.234] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:76:0)
[18:53:10.234] Timer tick, asking Buildbucket for the build status
[18:53:10.287] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:10.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:77:0) after 1m0s
[18:54:10.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:77:0)
[18:54:10.302] Timer tick, asking Buildbucket for the build status
[18:54:10.526] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:10.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:78:0) after 1m0s
[18:55:10.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:78:0)
[18:55:10.529] Timer tick, asking Buildbucket for the build status
[18:55:10.615] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:10.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:79:0) after 1m0s
[18:56:10.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:79:0)
[18:56:10.688] Timer tick, asking Buildbucket for the build status
[18:56:10.987] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:10.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:80:0) after 1m0s
[18:57:11.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:80:0)
[18:57:11.068] Timer tick, asking Buildbucket for the build status
[18:57:11.183] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:11.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:81:0) after 1m0s
[18:58:11.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:81:0)
[18:58:11.387] Timer tick, asking Buildbucket for the build status
[18:58:11.485] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:11.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:82:0) after 1m0s
[18:59:11.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:82:0)
[18:59:11.521] Timer tick, asking Buildbucket for the build status
[18:59:12.155] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:12.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:83:0) after 1m0s
[19:00:12.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:83:0)
[19:00:12.264] Timer tick, asking Buildbucket for the build status
[19:00:12.442] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:12.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:84:0) after 1m0s
[19:01:13.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:84:0)
[19:01:13.042] Timer tick, asking Buildbucket for the build status
[19:01:13.108] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:13.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:85:0) after 1m0s
[19:02:13.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:85:0)
[19:02:13.304] Timer tick, asking Buildbucket for the build status
[19:02:13.364] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:13.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:86:0) after 1m0s
[19:03:13.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:86:0)
[19:03:13.514] Timer tick, asking Buildbucket for the build status
[19:03:13.607] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:13.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:87:0) after 1m0s
[19:04:13.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:87:0)
[19:04:13.855] Timer tick, asking Buildbucket for the build status
[19:04:14.082] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:14.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:88:0) after 1m0s
[19:05:14.134] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:88:0)
[19:05:14.134] Timer tick, asking Buildbucket for the build status
[19:05:14.235] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:14.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:89:0) after 1m0s
[19:06:14.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:89:0)
[19:06:14.397] Timer tick, asking Buildbucket for the build status
[19:06:14.592] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:14.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:90:0) after 1m0s
[19:07:14.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:90:0)
[19:07:14.642] Timer tick, asking Buildbucket for the build status
[19:07:14.719] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:14.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:91:0) after 1m0s
[19:08:14.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:91:0)
[19:08:14.811] Timer tick, asking Buildbucket for the build status
[19:08:14.915] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:14.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:92:0) after 1m0s
[19:09:14.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:92:0)
[19:09:14.942] Timer tick, asking Buildbucket for the build status
[19:09:16.674] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:16.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:93:0) after 1m0s
[19:10:16.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:93:0)
[19:10:16.697] Timer tick, asking Buildbucket for the build status
[19:10:16.759] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:16.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:94:0) after 1m0s
[19:11:16.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:94:0)
[19:11:16.883] Timer tick, asking Buildbucket for the build status
[19:11:17.070] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:17.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:95:0) after 1m0s
[19:12:17.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:95:0)
[19:12:17.157] Timer tick, asking Buildbucket for the build status
[19:12:17.259] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:17.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:96:0) after 1m0s
[19:13:17.373] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:96:0)
[19:13:17.373] Timer tick, asking Buildbucket for the build status
[19:13:17.447] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:17.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:97:0) after 1m0s
[19:14:17.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:97:0)
[19:14:17.482] Timer tick, asking Buildbucket for the build status
[19:14:17.639] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:17.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:98:0) after 1m0s
[19:15:17.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:98:0)
[19:15:17.999] Timer tick, asking Buildbucket for the build status
[19:15:18.059] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:18.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:99:0) after 1m0s
[19:16:18.198] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:99:0)
[19:16:18.198] Timer tick, asking Buildbucket for the build status
[19:16:18.272] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:18.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:100:0) after 1m0s
[19:17:18.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:100:0)
[19:17:18.362] Timer tick, asking Buildbucket for the build status
[19:17:18.496] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:18.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:101:0) after 1m0s
[19:18:18.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:101:0)
[19:18:18.554] Timer tick, asking Buildbucket for the build status
[19:18:18.646] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:18.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:102:0) after 1m0s
[19:19:18.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:102:0)
[19:19:18.757] Timer tick, asking Buildbucket for the build status
[19:19:19.387] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:19.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:103:0) after 1m0s
[19:20:19.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:103:0)
[19:20:19.554] Timer tick, asking Buildbucket for the build status
[19:20:19.668] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:19.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:104:0) after 1m0s
[19:21:19.724] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:104:0)
[19:21:19.724] Timer tick, asking Buildbucket for the build status
[19:21:19.871] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:19.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:105:0) after 1m0s
[19:22:19.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:105:0)
[19:22:19.958] Timer tick, asking Buildbucket for the build status
[19:22:20.028] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:20.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:106:0) after 1m0s
[19:23:20.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:106:0)
[19:23:20.122] Timer tick, asking Buildbucket for the build status
[19:23:20.236] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:20.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:107:0) after 1m0s
[19:24:20.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:107:0)
[19:24:20.263] Timer tick, asking Buildbucket for the build status
[19:24:20.363] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:20.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:108:0) after 1m0s
[19:25:20.450] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:108:0)
[19:25:20.450] Timer tick, asking Buildbucket for the build status
[19:25:20.546] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:20.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:109:0) after 1m0s
[19:26:20.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:109:0)
[19:26:20.604] Timer tick, asking Buildbucket for the build status
[19:26:20.723] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:20.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:110:0) after 1m0s
[19:27:20.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:110:0)
[19:27:20.761] Timer tick, asking Buildbucket for the build status
[19:27:20.855] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:20.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:111:0) after 1m0s
[19:28:20.866] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:111:0)
[19:28:20.866] Timer tick, asking Buildbucket for the build status
[19:28:21.165] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:21.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:112:0) after 1m0s
[19:29:21.212] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:112:0)
[19:29:21.212] Timer tick, asking Buildbucket for the build status
[19:29:21.506] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:21.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:113:0) after 1m0s
[19:30:21.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:113:0)
[19:30:21.533] Timer tick, asking Buildbucket for the build status
[19:30:21.778] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:21.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:114:0) after 1m0s
[19:31:21.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:114:0)
[19:31:21.816] Timer tick, asking Buildbucket for the build status
[19:31:22.259] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:22.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:115:0) after 1m0s
[19:32:22.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:115:0)
[19:32:22.313] Timer tick, asking Buildbucket for the build status
[19:32:22.394] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:22.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:116:0) after 1m0s
[19:33:22.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:116:0)
[19:33:22.414] Timer tick, asking Buildbucket for the build status
[19:33:22.489] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:22.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:117:0) after 1m0s
[19:34:22.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:117:0)
[19:34:22.539] Timer tick, asking Buildbucket for the build status
[19:34:22.864] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:22.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:118:0) after 1m0s
[19:35:22.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:118:0)
[19:35:22.938] Timer tick, asking Buildbucket for the build status
[19:35:23.038] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:23.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:119:0) after 1m0s
[19:36:23.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:119:0)
[19:36:23.041] Timer tick, asking Buildbucket for the build status
[19:36:23.127] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:23.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:120:0) after 1m0s
[19:37:23.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:120:0)
[19:37:23.158] Timer tick, asking Buildbucket for the build status
[19:37:25.046] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:25.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:121:0) after 1m0s
[19:38:25.110] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:121:0)
[19:38:25.110] Timer tick, asking Buildbucket for the build status
[19:38:25.174] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:25.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:122:0) after 1m0s
[19:39:25.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:122:0)
[19:39:25.254] Timer tick, asking Buildbucket for the build status
[19:39:25.748] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:25.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:123:0) after 1m0s
[19:40:25.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:123:0)
[19:40:25.779] Timer tick, asking Buildbucket for the build status
[19:40:25.849] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:25.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:124:0) after 1m0s
[19:41:25.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:124:0)
[19:41:25.918] Timer tick, asking Buildbucket for the build status
[19:41:25.985] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:25.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:125:0) after 1m0s
[19:42:26.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:125:0)
[19:42:26.052] Timer tick, asking Buildbucket for the build status
[19:42:26.213] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:26.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:126:0) after 1m0s
[19:43:26.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:126:0)
[19:43:26.247] Timer tick, asking Buildbucket for the build status
[19:43:27.126] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:27.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:127:0) after 1m0s
[19:44:27.191] Handling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:127:0)
[19:44:27.191] Timer tick, asking Buildbucket for the build status
[19:44:27.294] Build 8858743228168439872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:27.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9024173290192666224:128:0) after 1m0s
[19:44:36.983] Received PubSub notification, asking Buildbucket for the build status
[19:44:37.069] Build 8858743228168439872: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:44:37.069] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610048675449529",
"created_by": "project:chromium",
"created_ts": "1610041129865463",
"id": "8858743228168439872",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7f22ba8212c4cbe91f8c441376ca48f1de672d57\"}}",
"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\": \"7f22ba8212c4cbe91f8c441376ca48f1de672d57\", \"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\": 1330, \"got_angle_revision\": \"d0a38010a14cf01b1a7b32143315108bd3334f46\", \"got_dawn_revision\": \"06aa1af28a72833fd59531763a0bee0338811f5b\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"7f22ba8212c4cbe91f8c441376ca48f1de672d57\", \"got_revision_cp\": \"refs/heads/master@{#841093}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7b2f938954180e8945f776d3e202f6a2bda1aaf9\", \"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\": \"7f22ba8212c4cbe91f8c441376ca48f1de672d57\"}, \"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-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": "1610041172348599",
"status": "COMPLETED",
"status_changed_ts": "1610048675737258",
"tags": [
"build_address:luci.chromium.ci/win-official/1330",
"builder:win-official",
"buildset:commit/git/7f22ba8212c4cbe91f8c441376ca48f1de672d57",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f22ba8212c4cbe91f8c441376ca48f1de672d57",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024173290192666224",
"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/8858743228168439872/+/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:50f6bf8743c56010",
"user_agent:luci-scheduler"
],
"updated_ts": "1610048675741330",
"url": "https://ci.chromium.org/b/8858743228168439872",
"utcnow_ts": "1610048677061809"
}
}
[19:44:37.069] Invocation finished in 2h5m48.517748327s with status SUCCEEDED