[19:03:52.312] New invocation is queued and will start shortly
[19:03:55.978] Starting the invocation (attempt 1)
[19:03:56.028] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:03:56.028] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:03:56.028] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9040203601581106256",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cc823262a0ef73c45f6f4aa0b80c568863da1702\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9040203601581106256",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/cc823262a0ef73c45f6f4aa0b80c568863da1702",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc823262a0ef73c45f6f4aa0b80c568863da1702",
"gitiles_ref:refs/heads/master"
]
}
[19:03:57.509] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1594753436140958",
"id": "8874773536903819584",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc823262a0ef73c45f6f4aa0b80c568863da1702\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1594753437041154",
"tags": [
"build_address:luci.chromium.ci/win-official/103",
"builder:win-official",
"buildset:commit/git/cc823262a0ef73c45f6f4aa0b80c568863da1702",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc823262a0ef73c45f6f4aa0b80c568863da1702",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040203601581106256",
"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/8874773536903819584/+/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": "1594753437041292",
"url": "https://ci.chromium.org/b/8874773536903819584",
"utcnow_ts": "1594753437496725"
}
}
[19:03:57.509] Task URL: https://ci.chromium.org/b/8874773536903819584
[19:03:57.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:2:0) after 1m0s
[19:04:10.683] Received PubSub notification, asking Buildbucket for the build status
[19:04:10.981] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:57.570] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:2:0)
[19:04:57.570] Timer tick, asking Buildbucket for the build status
[19:04:57.657] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:57.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:4:0) after 1m0s
[19:05:57.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:4:0)
[19:05:57.744] Timer tick, asking Buildbucket for the build status
[19:05:57.861] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:57.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:5:0) after 1m0s
[19:06:57.922] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:5:0)
[19:06:57.922] Timer tick, asking Buildbucket for the build status
[19:06:58.169] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:58.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:6:0) after 1m0s
[19:07:58.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:6:0)
[19:07:58.208] Timer tick, asking Buildbucket for the build status
[19:07:58.361] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:58.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:7:0) after 1m0s
[19:08:58.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:7:0)
[19:08:58.383] Timer tick, asking Buildbucket for the build status
[19:08:58.649] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:58.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:8:0) after 1m0s
[19:09:58.705] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:8:0)
[19:09:58.705] Timer tick, asking Buildbucket for the build status
[19:09:58.880] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:58.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:9:0) after 1m0s
[19:10:58.950] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:9:0)
[19:10:58.950] Timer tick, asking Buildbucket for the build status
[19:10:59.420] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:59.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:10:0) after 1m0s
[19:11:59.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:10:0)
[19:11:59.510] Timer tick, asking Buildbucket for the build status
[19:11:59.849] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:59.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:11:0) after 1m0s
[19:12:59.917] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:11:0)
[19:12:59.917] Timer tick, asking Buildbucket for the build status
[19:13:00.179] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:00.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:12:0) after 1m0s
[19:14:00.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:12:0)
[19:14:00.348] Timer tick, asking Buildbucket for the build status
[19:14:00.767] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:00.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:13:0) after 1m0s
[19:15:01.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:13:0)
[19:15:01.640] Timer tick, asking Buildbucket for the build status
[19:15:02.074] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:02.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:14:0) after 1m0s
[19:16:02.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:14:0)
[19:16:02.425] Timer tick, asking Buildbucket for the build status
[19:16:04.827] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:04.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:15:0) after 1m0s
[19:17:04.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:15:0)
[19:17:04.938] Timer tick, asking Buildbucket for the build status
[19:17:05.577] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:05.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:16:0) after 1m0s
[19:18:05.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:16:0)
[19:18:05.647] Timer tick, asking Buildbucket for the build status
[19:18:06.232] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:06.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:17:0) after 1m0s
[19:19:06.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:17:0)
[19:19:06.254] Timer tick, asking Buildbucket for the build status
[19:19:06.514] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:06.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:18:0) after 1m0s
[19:20:06.557] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:18:0)
[19:20:06.557] Timer tick, asking Buildbucket for the build status
[19:20:06.708] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:06.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:19:0) after 1m0s
[19:21:06.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:19:0)
[19:21:06.742] Timer tick, asking Buildbucket for the build status
[19:21:06.882] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:06.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:20:0) after 1m0s
[19:22:06.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:20:0)
[19:22:06.960] Timer tick, asking Buildbucket for the build status
[19:22:07.659] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:07.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:21:0) after 1m0s
[19:23:07.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:21:0)
[19:23:07.723] Timer tick, asking Buildbucket for the build status
[19:23:07.854] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:07.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:22:0) after 1m0s
[19:24:07.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:22:0)
[19:24:07.891] Timer tick, asking Buildbucket for the build status
[19:24:08.186] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:08.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:23:0) after 1m0s
[19:25:09.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:23:0)
[19:25:09.142] Timer tick, asking Buildbucket for the build status
[19:25:09.673] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:09.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:24:0) after 1m0s
[19:26:09.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:24:0)
[19:26:09.780] Timer tick, asking Buildbucket for the build status
[19:26:10.255] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:10.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:25:0) after 1m0s
[19:27:10.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:25:0)
[19:27:10.237] Timer tick, asking Buildbucket for the build status
[19:27:10.376] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:10.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:26:0) after 1m0s
[19:28:10.643] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:26:0)
[19:28:10.643] Timer tick, asking Buildbucket for the build status
[19:28:10.786] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:10.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:27:0) after 1m0s
[19:29:10.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:27:0)
[19:29:10.854] Timer tick, asking Buildbucket for the build status
[19:29:11.056] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:11.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:28:0) after 1m0s
[19:30:11.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:28:0)
[19:30:11.132] Timer tick, asking Buildbucket for the build status
[19:30:11.352] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:11.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:29:0) after 1m0s
[19:31:11.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:29:0)
[19:31:11.503] Timer tick, asking Buildbucket for the build status
[19:31:11.784] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:11.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:30:0) after 1m0s
[19:32:11.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:30:0)
[19:32:11.937] Timer tick, asking Buildbucket for the build status
[19:32:12.633] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:12.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:31:0) after 1m0s
[19:33:12.769] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:31:0)
[19:33:12.769] Timer tick, asking Buildbucket for the build status
[19:33:13.002] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:13.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:32:0) after 1m0s
[19:34:13.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:32:0)
[19:34:13.131] Timer tick, asking Buildbucket for the build status
[19:34:13.294] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:13.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:33:0) after 1m0s
[19:35:13.353] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:33:0)
[19:35:13.353] Timer tick, asking Buildbucket for the build status
[19:35:13.791] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:13.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:34:0) after 1m0s
[19:36:13.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:34:0)
[19:36:13.844] Timer tick, asking Buildbucket for the build status
[19:36:14.118] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:14.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:35:0) after 1m0s
[19:37:14.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:35:0)
[19:37:14.164] Timer tick, asking Buildbucket for the build status
[19:37:14.384] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:14.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:36:0) after 1m0s
[19:38:14.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:36:0)
[19:38:14.438] Timer tick, asking Buildbucket for the build status
[19:38:14.876] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:14.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:37:0) after 1m0s
[19:39:14.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:37:0)
[19:39:14.854] Timer tick, asking Buildbucket for the build status
[19:39:14.994] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:14.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:38:0) after 1m0s
[19:40:15.048] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:38:0)
[19:40:15.048] Timer tick, asking Buildbucket for the build status
[19:40:15.490] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:15.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:39:0) after 1m0s
[19:41:15.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:39:0)
[19:41:15.524] Timer tick, asking Buildbucket for the build status
[19:41:15.845] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:15.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:40:0) after 1m0s
[19:42:15.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:40:0)
[19:42:15.886] Timer tick, asking Buildbucket for the build status
[19:42:17.521] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:17.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:41:0) after 1m0s
[19:43:17.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:41:0)
[19:43:17.585] Timer tick, asking Buildbucket for the build status
[19:43:17.868] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:17.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:42:0) after 1m0s
[19:44:17.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:42:0)
[19:44:17.930] Timer tick, asking Buildbucket for the build status
[19:44:18.208] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:18.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:43:0) after 1m0s
[19:45:18.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:43:0)
[19:45:18.269] Timer tick, asking Buildbucket for the build status
[19:45:18.458] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:18.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:44:0) after 1m0s
[19:46:18.550] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:44:0)
[19:46:18.550] Timer tick, asking Buildbucket for the build status
[19:46:18.814] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:18.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:45:0) after 1m0s
[19:47:18.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:45:0)
[19:47:18.839] Timer tick, asking Buildbucket for the build status
[19:47:19.022] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:19.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:46:0) after 1m0s
[19:48:19.098] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:46:0)
[19:48:19.098] Timer tick, asking Buildbucket for the build status
[19:48:19.226] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:19.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:47:0) after 1m0s
[19:49:19.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:47:0)
[19:49:19.261] Timer tick, asking Buildbucket for the build status
[19:49:19.521] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:19.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:48:0) after 1m0s
[19:50:19.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:48:0)
[19:50:19.549] Timer tick, asking Buildbucket for the build status
[19:50:19.758] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:19.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:49:0) after 1m0s
[19:51:19.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:49:0)
[19:51:19.784] Timer tick, asking Buildbucket for the build status
[19:51:19.916] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:19.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:50:0) after 1m0s
[19:52:19.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:50:0)
[19:52:19.982] Timer tick, asking Buildbucket for the build status
[19:52:20.182] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:20.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:51:0) after 1m0s
[19:53:20.343] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:51:0)
[19:53:20.343] Timer tick, asking Buildbucket for the build status
[19:53:20.523] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:20.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:52:0) after 1m0s
[19:54:20.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:52:0)
[19:54:20.669] Timer tick, asking Buildbucket for the build status
[19:54:20.925] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:20.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:53:0) after 1m0s
[19:55:21.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:53:0)
[19:55:21.185] Timer tick, asking Buildbucket for the build status
[19:55:21.333] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:21.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:54:0) after 1m0s
[19:56:21.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:54:0)
[19:56:21.364] Timer tick, asking Buildbucket for the build status
[19:56:21.498] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:21.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:55:0) after 1m0s
[19:57:21.523] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:55:0)
[19:57:21.523] Timer tick, asking Buildbucket for the build status
[19:57:21.691] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:21.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:56:0) after 1m0s
[19:58:21.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:56:0)
[19:58:21.819] Timer tick, asking Buildbucket for the build status
[19:58:22.198] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:22.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:57:0) after 1m0s
[19:59:22.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:57:0)
[19:59:22.269] Timer tick, asking Buildbucket for the build status
[19:59:22.997] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:22.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:58:0) after 1m0s
[20:00:23.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:58:0)
[20:00:23.079] Timer tick, asking Buildbucket for the build status
[20:00:23.557] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:23.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:59:0) after 1m0s
[20:01:23.570] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:59:0)
[20:01:23.570] Timer tick, asking Buildbucket for the build status
[20:01:24.092] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:24.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:60:0) after 1m0s
[20:02:24.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:60:0)
[20:02:24.126] Timer tick, asking Buildbucket for the build status
[20:02:24.271] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:24.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:61:0) after 1m0s
[20:03:24.308] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:61:0)
[20:03:24.308] Timer tick, asking Buildbucket for the build status
[20:03:24.529] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:24.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:62:0) after 1m0s
[20:04:24.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:62:0)
[20:04:24.650] Timer tick, asking Buildbucket for the build status
[20:04:24.980] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:24.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:63:0) after 1m0s
[20:05:25.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:63:0)
[20:05:25.105] Timer tick, asking Buildbucket for the build status
[20:05:25.919] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:25.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:64:0) after 1m0s
[20:06:26.024] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:64:0)
[20:06:26.024] Timer tick, asking Buildbucket for the build status
[20:06:26.300] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:26.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:65:0) after 1m0s
[20:07:26.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:65:0)
[20:07:26.502] Timer tick, asking Buildbucket for the build status
[20:07:26.925] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:26.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:66:0) after 1m0s
[20:08:27.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:66:0)
[20:08:27.107] Timer tick, asking Buildbucket for the build status
[20:08:27.251] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:27.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:67:0) after 1m0s
[20:09:27.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:67:0)
[20:09:27.405] Timer tick, asking Buildbucket for the build status
[20:09:27.539] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:27.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:68:0) after 1m0s
[20:10:27.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:68:0)
[20:10:27.599] Timer tick, asking Buildbucket for the build status
[20:10:27.816] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:27.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:69:0) after 1m0s
[20:11:27.964] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:69:0)
[20:11:27.964] Timer tick, asking Buildbucket for the build status
[20:11:28.343] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:28.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:70:0) after 1m0s
[20:12:28.435] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:70:0)
[20:12:28.435] Timer tick, asking Buildbucket for the build status
[20:12:28.558] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:28.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:71:0) after 1m0s
[20:13:28.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:71:0)
[20:13:28.660] Timer tick, asking Buildbucket for the build status
[20:13:28.790] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:28.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:72:0) after 1m0s
[20:14:29.007] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:72:0)
[20:14:29.007] Timer tick, asking Buildbucket for the build status
[20:14:29.201] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:29.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:73:0) after 1m0s
[20:15:29.329] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:73:0)
[20:15:29.329] Timer tick, asking Buildbucket for the build status
[20:15:29.493] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:29.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:74:0) after 1m0s
[20:16:29.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:74:0)
[20:16:29.520] Timer tick, asking Buildbucket for the build status
[20:16:29.727] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:29.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:75:0) after 1m0s
[20:17:29.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:75:0)
[20:17:29.896] Timer tick, asking Buildbucket for the build status
[20:17:30.026] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:30.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:76:0) after 1m0s
[20:18:30.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:76:0)
[20:18:30.165] Timer tick, asking Buildbucket for the build status
[20:18:30.380] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:30.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:77:0) after 1m0s
[20:19:30.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:77:0)
[20:19:30.469] Timer tick, asking Buildbucket for the build status
[20:19:30.836] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:30.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:78:0) after 1m0s
[20:20:30.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:78:0)
[20:20:30.933] Timer tick, asking Buildbucket for the build status
[20:20:31.092] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:31.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:79:0) after 1m0s
[20:21:31.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:79:0)
[20:21:31.209] Timer tick, asking Buildbucket for the build status
[20:21:31.335] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:31.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:80:0) after 1m0s
[20:22:31.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:80:0)
[20:22:31.398] Timer tick, asking Buildbucket for the build status
[20:22:31.705] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:31.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:81:0) after 1m0s
[20:23:31.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:81:0)
[20:23:31.774] Timer tick, asking Buildbucket for the build status
[20:23:32.254] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:32.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:82:0) after 1m0s
[20:24:32.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:82:0)
[20:24:32.422] Timer tick, asking Buildbucket for the build status
[20:24:32.854] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:32.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:83:0) after 1m0s
[20:25:32.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:83:0)
[20:25:32.945] Timer tick, asking Buildbucket for the build status
[20:25:33.138] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:33.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:84:0) after 1m0s
[20:26:33.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:84:0)
[20:26:33.254] Timer tick, asking Buildbucket for the build status
[20:26:33.567] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:33.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:85:0) after 1m0s
[20:27:33.977] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:85:0)
[20:27:33.977] Timer tick, asking Buildbucket for the build status
[20:27:34.386] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:34.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:86:0) after 1m0s
[20:28:34.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:86:0)
[20:28:34.470] Timer tick, asking Buildbucket for the build status
[20:28:34.638] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:34.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:87:0) after 1m0s
[20:29:34.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:87:0)
[20:29:34.695] Timer tick, asking Buildbucket for the build status
[20:29:34.858] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:34.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:88:0) after 1m0s
[20:30:34.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:88:0)
[20:30:34.949] Timer tick, asking Buildbucket for the build status
[20:30:35.196] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:35.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:89:0) after 1m0s
[20:31:35.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:89:0)
[20:31:35.266] Timer tick, asking Buildbucket for the build status
[20:31:35.565] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:35.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:90:0) after 1m0s
[20:32:35.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:90:0)
[20:32:35.804] Timer tick, asking Buildbucket for the build status
[20:32:36.301] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:36.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:91:0) after 1m0s
[20:33:36.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:91:0)
[20:33:36.398] Timer tick, asking Buildbucket for the build status
[20:33:36.523] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:36.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:92:0) after 1m0s
[20:34:36.703] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:92:0)
[20:34:36.703] Timer tick, asking Buildbucket for the build status
[20:34:37.146] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:37.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:93:0) after 1m0s
[20:35:37.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:93:0)
[20:35:37.205] Timer tick, asking Buildbucket for the build status
[20:35:40.195] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:40.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:94:0) after 1m0s
[20:36:40.472] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:94:0)
[20:36:40.472] Timer tick, asking Buildbucket for the build status
[20:36:40.906] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:40.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:95:0) after 1m0s
[20:37:40.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:95:0)
[20:37:40.983] Timer tick, asking Buildbucket for the build status
[20:37:41.222] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:41.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:96:0) after 1m0s
[20:38:41.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:96:0)
[20:38:41.243] Timer tick, asking Buildbucket for the build status
[20:38:41.405] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:41.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:97:0) after 1m0s
[20:39:41.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:97:0)
[20:39:41.440] Timer tick, asking Buildbucket for the build status
[20:39:41.699] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:41.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:98:0) after 1m0s
[20:40:41.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:98:0)
[20:40:41.712] Timer tick, asking Buildbucket for the build status
[20:40:41.816] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:41.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:99:0) after 1m0s
[20:41:41.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:99:0)
[20:41:41.870] Timer tick, asking Buildbucket for the build status
[20:41:42.910] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:42.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:100:0) after 1m0s
[20:42:43.011] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:100:0)
[20:42:43.011] Timer tick, asking Buildbucket for the build status
[20:42:43.327] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:43.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:101:0) after 1m0s
[20:43:43.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:101:0)
[20:43:43.415] Timer tick, asking Buildbucket for the build status
[20:43:43.697] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:43.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:102:0) after 1m0s
[20:44:43.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:102:0)
[20:44:43.731] Timer tick, asking Buildbucket for the build status
[20:44:43.853] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:43.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:103:0) after 1m0s
[20:45:43.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:103:0)
[20:45:43.870] Timer tick, asking Buildbucket for the build status
[20:45:44.012] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:44.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:104:0) after 1m0s
[20:46:44.063] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:104:0)
[20:46:44.063] Timer tick, asking Buildbucket for the build status
[20:46:44.316] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:44.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:105:0) after 1m0s
[20:47:44.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:105:0)
[20:47:44.361] Timer tick, asking Buildbucket for the build status
[20:47:44.557] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:44.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:106:0) after 1m0s
[20:48:44.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:106:0)
[20:48:44.569] Timer tick, asking Buildbucket for the build status
[20:48:44.810] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:44.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:107:0) after 1m0s
[20:49:44.995] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:107:0)
[20:49:44.995] Timer tick, asking Buildbucket for the build status
[20:49:45.169] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:45.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:108:0) after 1m0s
[20:50:45.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:108:0)
[20:50:45.277] Timer tick, asking Buildbucket for the build status
[20:50:45.437] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:45.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:109:0) after 1m0s
[20:51:45.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:109:0)
[20:51:45.513] Timer tick, asking Buildbucket for the build status
[20:51:45.617] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:45.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:110:0) after 1m0s
[20:52:45.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:110:0)
[20:52:45.775] Timer tick, asking Buildbucket for the build status
[20:52:45.910] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:45.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:111:0) after 1m0s
[20:53:46.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:111:0)
[20:53:46.094] Timer tick, asking Buildbucket for the build status
[20:53:46.589] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:46.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:112:0) after 1m0s
[20:54:46.628] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:112:0)
[20:54:46.628] Timer tick, asking Buildbucket for the build status
[20:54:47.536] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:47.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:113:0) after 1m0s
[20:55:47.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:113:0)
[20:55:47.585] Timer tick, asking Buildbucket for the build status
[20:55:47.741] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:47.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:114:0) after 1m0s
[20:56:47.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:114:0)
[20:56:47.761] Timer tick, asking Buildbucket for the build status
[20:56:49.690] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:49.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:115:0) after 1m0s
[20:57:49.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:115:0)
[20:57:49.707] Timer tick, asking Buildbucket for the build status
[20:57:49.921] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:49.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:116:0) after 1m0s
[20:58:50.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:116:0)
[20:58:50.246] Timer tick, asking Buildbucket for the build status
[20:58:50.457] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:50.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:117:0) after 1m0s
[20:59:50.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:117:0)
[20:59:50.608] Timer tick, asking Buildbucket for the build status
[20:59:51.115] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:51.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:118:0) after 1m0s
[21:00:51.206] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:118:0)
[21:00:51.206] Timer tick, asking Buildbucket for the build status
[21:00:51.461] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:51.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:119:0) after 1m0s
[21:01:51.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:119:0)
[21:01:51.571] Timer tick, asking Buildbucket for the build status
[21:01:51.711] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:51.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:120:0) after 1m0s
[21:02:51.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:120:0)
[21:02:51.760] Timer tick, asking Buildbucket for the build status
[21:02:52.751] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:52.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:121:0) after 1m0s
[21:03:52.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:121:0)
[21:03:52.899] Timer tick, asking Buildbucket for the build status
[21:03:53.356] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:53.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:122:0) after 1m0s
[21:04:53.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:122:0)
[21:04:53.402] Timer tick, asking Buildbucket for the build status
[21:04:54.369] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:54.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:123:0) after 1m0s
[21:05:54.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:123:0)
[21:05:54.416] Timer tick, asking Buildbucket for the build status
[21:05:55.151] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:55.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:124:0) after 1m0s
[21:06:55.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:124:0)
[21:06:55.141] Timer tick, asking Buildbucket for the build status
[21:06:55.365] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:55.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:125:0) after 1m0s
[21:07:55.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:125:0)
[21:07:55.431] Timer tick, asking Buildbucket for the build status
[21:07:55.600] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:55.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:126:0) after 1m0s
[21:08:55.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:126:0)
[21:08:55.779] Timer tick, asking Buildbucket for the build status
[21:08:55.929] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:55.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:127:0) after 1m0s
[21:09:56.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:127:0)
[21:09:56.173] Timer tick, asking Buildbucket for the build status
[21:09:56.493] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:56.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:128:0) after 1m0s
[21:10:56.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:128:0)
[21:10:56.970] Timer tick, asking Buildbucket for the build status
[21:10:59.654] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:59.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:129:0) after 1m0s
[21:11:59.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:129:0)
[21:11:59.731] Timer tick, asking Buildbucket for the build status
[21:11:59.924] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:59.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:130:0) after 1m0s
[21:12:59.988] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:130:0)
[21:12:59.988] Timer tick, asking Buildbucket for the build status
[21:13:00.222] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:00.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:131:0) after 1m0s
[21:14:00.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:131:0)
[21:14:00.368] Timer tick, asking Buildbucket for the build status
[21:14:00.688] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:00.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:132:0) after 1m0s
[21:15:00.778] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:132:0)
[21:15:00.778] Timer tick, asking Buildbucket for the build status
[21:15:01.122] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:01.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:133:0) after 1m0s
[21:16:02.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:133:0)
[21:16:02.185] Timer tick, asking Buildbucket for the build status
[21:16:02.482] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:02.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:134:0) after 1m0s
[21:17:02.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:134:0)
[21:17:02.772] Timer tick, asking Buildbucket for the build status
[21:17:02.940] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:02.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:135:0) after 1m0s
[21:18:03.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:135:0)
[21:18:03.042] Timer tick, asking Buildbucket for the build status
[21:18:03.358] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:03.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:136:0) after 1m0s
[21:19:03.385] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:136:0)
[21:19:03.385] Timer tick, asking Buildbucket for the build status
[21:19:03.681] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:03.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:137:0) after 1m0s
[21:20:03.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:137:0)
[21:20:03.777] Timer tick, asking Buildbucket for the build status
[21:20:03.912] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:03.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:138:0) after 1m0s
[21:21:04.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:138:0)
[21:21:04.005] Timer tick, asking Buildbucket for the build status
[21:21:04.148] Build 8874773536903819584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:04.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9040203601581106256:139:0) after 1m0s
[21:21:30.796] Received PubSub notification, asking Buildbucket for the build status
[21:21:30.859] Build 8874773536903819584: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:21:30.859] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1594761689130742",
"created_by": "project:chromium",
"created_ts": "1594753436140958",
"id": "8874773536903819584",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc823262a0ef73c45f6f4aa0b80c568863da1702\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-pi5z\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 103, \"got_angle_revision\": \"249db6da5a6be512eee7b2680b0869960055ece2\", \"got_dawn_revision\": \"4e2d7cff8fc304aa9cd7cb6476c7ff72f289ed95\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"cc823262a0ef73c45f6f4aa0b80c568863da1702\", \"got_revision_cp\": \"refs/heads/master@{#788283}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"cd0af6456eb1c427943c2cf60d4f6234f821aa09\", \"got_v8_revision\": \"91280009b2d14b754859306d41de232b431517ea\", \"got_v8_revision_cp\": \"refs/heads/8.6.129@{#1}\", \"got_webrtc_revision\": \"8df59bc74ebb4d0b8e5d80a8b4f5c674db2a9c4d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31703}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc823262a0ef73c45f6f4aa0b80c568863da1702\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-pi5z\"], \"image\": [\"chrome-win10-1703-20070700-8a9e7990cc1\"], \"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\": [\"5378-e275056\"], \"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": "1594753449472151",
"status": "COMPLETED",
"status_changed_ts": "1594761689910926",
"tags": [
"build_address:luci.chromium.ci/win-official/103",
"builder:win-official",
"buildset:commit/git/cc823262a0ef73c45f6f4aa0b80c568863da1702",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc823262a0ef73c45f6f4aa0b80c568863da1702",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040203601581106256",
"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/8874773536903819584/+/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:4d6787edcfd92a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1594761690035770",
"url": "https://ci.chromium.org/b/8874773536903819584",
"utcnow_ts": "1594761690848331"
}
}
[21:21:30.859] Invocation finished in 2h17m38.56321039s with status SUCCEEDED