[00:16:52.728] New invocation is queued and will start shortly
[00:16:54.184] Starting the invocation (attempt 1)
[00:16:54.228] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:16:54.228] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:16:54.228] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9039187342260428176",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cb64bcc45e898b9e7cb5db28e23acb56e422132a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9039187342260428176",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/cb64bcc45e898b9e7cb5db28e23acb56e422132a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cb64bcc45e898b9e7cb5db28e23acb56e422132a",
"gitiles_ref:refs/heads/master"
]
}
[00:16:54.874] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1595722614331448",
"id": "8873757279913096800",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cb64bcc45e898b9e7cb5db28e23acb56e422132a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1595722614760934",
"tags": [
"build_address:luci.chromium.ci/win-official/175",
"builder:win-official",
"buildset:commit/git/cb64bcc45e898b9e7cb5db28e23acb56e422132a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cb64bcc45e898b9e7cb5db28e23acb56e422132a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039187342260428176",
"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/8873757279913096800/+/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": "1595722614761124",
"url": "https://ci.chromium.org/b/8873757279913096800",
"utcnow_ts": "1595722614866897"
}
}
[00:16:54.874] Task URL: https://ci.chromium.org/b/8873757279913096800
[00:16:54.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:2:0) after 1m0s
[00:17:09.031] Received PubSub notification, asking Buildbucket for the build status
[00:17:09.146] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:54.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:2:0)
[00:17:54.972] Timer tick, asking Buildbucket for the build status
[00:17:55.285] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:55.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:4:0) after 1m0s
[00:18:55.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:4:0)
[00:18:55.277] Timer tick, asking Buildbucket for the build status
[00:18:55.702] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:55.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:5:0) after 1m0s
[00:19:55.725] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:5:0)
[00:19:55.725] Timer tick, asking Buildbucket for the build status
[00:19:55.787] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:55.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:6:0) after 1m0s
[00:20:55.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:6:0)
[00:20:55.785] Timer tick, asking Buildbucket for the build status
[00:20:55.845] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:55.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:7:0) after 1m0s
[00:21:55.885] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:7:0)
[00:21:55.885] Timer tick, asking Buildbucket for the build status
[00:21:56.220] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:56.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:8:0) after 1m0s
[00:22:56.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:8:0)
[00:22:56.326] Timer tick, asking Buildbucket for the build status
[00:22:56.447] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:56.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:9:0) after 1m0s
[00:23:56.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:9:0)
[00:23:56.515] Timer tick, asking Buildbucket for the build status
[00:23:56.657] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:56.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:10:0) after 1m0s
[00:24:56.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:10:0)
[00:24:56.719] Timer tick, asking Buildbucket for the build status
[00:24:56.983] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:56.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:11:0) after 1m0s
[00:25:57.198] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:11:0)
[00:25:57.198] Timer tick, asking Buildbucket for the build status
[00:25:57.438] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:57.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:12:0) after 1m0s
[00:26:57.609] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:12:0)
[00:26:57.609] Timer tick, asking Buildbucket for the build status
[00:26:57.990] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:57.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:13:0) after 1m0s
[00:27:58.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:13:0)
[00:27:58.231] Timer tick, asking Buildbucket for the build status
[00:27:58.302] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:58.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:14:0) after 1m0s
[00:28:58.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:14:0)
[00:28:58.530] Timer tick, asking Buildbucket for the build status
[00:28:58.745] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:58.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:15:0) after 1m0s
[00:29:58.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:15:0)
[00:29:58.905] Timer tick, asking Buildbucket for the build status
[00:29:59.000] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:59.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:16:0) after 1m0s
[00:30:59.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:16:0)
[00:30:59.045] Timer tick, asking Buildbucket for the build status
[00:30:59.141] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:59.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:17:0) after 1m0s
[00:32:00.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:17:0)
[00:32:00.404] Timer tick, asking Buildbucket for the build status
[00:32:00.517] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:00.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:18:0) after 1m0s
[00:33:00.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:18:0)
[00:33:00.685] Timer tick, asking Buildbucket for the build status
[00:33:00.764] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:00.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:19:0) after 1m0s
[00:34:00.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:19:0)
[00:34:00.819] Timer tick, asking Buildbucket for the build status
[00:34:01.002] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:01.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:20:0) after 1m0s
[00:35:01.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:20:0)
[00:35:01.189] Timer tick, asking Buildbucket for the build status
[00:35:01.268] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:01.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:21:0) after 1m0s
[00:36:01.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:21:0)
[00:36:01.663] Timer tick, asking Buildbucket for the build status
[00:36:01.725] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:01.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:22:0) after 1m0s
[00:37:01.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:22:0)
[00:37:01.758] Timer tick, asking Buildbucket for the build status
[00:37:02.475] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:02.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:23:0) after 1m0s
[00:38:02.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:23:0)
[00:38:02.548] Timer tick, asking Buildbucket for the build status
[00:38:02.889] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:02.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:24:0) after 1m0s
[00:39:02.946] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:24:0)
[00:39:02.946] Timer tick, asking Buildbucket for the build status
[00:39:03.014] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:03.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:25:0) after 1m0s
[00:40:03.203] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:25:0)
[00:40:03.203] Timer tick, asking Buildbucket for the build status
[00:40:03.291] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:03.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:26:0) after 1m0s
[00:41:03.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:26:0)
[00:41:03.484] Timer tick, asking Buildbucket for the build status
[00:41:03.572] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:03.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:27:0) after 1m0s
[00:42:03.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:27:0)
[00:42:03.745] Timer tick, asking Buildbucket for the build status
[00:42:04.095] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:04.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:28:0) after 1m0s
[00:43:04.210] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:28:0)
[00:43:04.210] Timer tick, asking Buildbucket for the build status
[00:43:04.272] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:04.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:29:0) after 1m0s
[00:44:04.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:29:0)
[00:44:04.533] Timer tick, asking Buildbucket for the build status
[00:44:04.688] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:04.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:30:0) after 1m0s
[00:45:04.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:30:0)
[00:45:04.754] Timer tick, asking Buildbucket for the build status
[00:45:05.025] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:05.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:31:0) after 1m0s
[00:46:05.200] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:31:0)
[00:46:05.200] Timer tick, asking Buildbucket for the build status
[00:46:05.423] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:05.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:32:0) after 1m0s
[00:47:05.543] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:32:0)
[00:47:05.543] Timer tick, asking Buildbucket for the build status
[00:47:05.898] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:05.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:33:0) after 1m0s
[00:48:06.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:33:0)
[00:48:06.111] Timer tick, asking Buildbucket for the build status
[00:48:06.167] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:06.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:34:0) after 1m0s
[00:49:06.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:34:0)
[00:49:06.293] Timer tick, asking Buildbucket for the build status
[00:49:06.371] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:06.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:35:0) after 1m0s
[00:50:06.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:35:0)
[00:50:06.503] Timer tick, asking Buildbucket for the build status
[00:50:06.687] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:06.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:36:0) after 1m0s
[00:51:07.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:36:0)
[00:51:07.155] Timer tick, asking Buildbucket for the build status
[00:51:07.324] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:07.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:37:0) after 1m0s
[00:52:07.511] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:37:0)
[00:52:07.511] Timer tick, asking Buildbucket for the build status
[00:52:07.680] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:07.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:38:0) after 1m0s
[00:53:07.790] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:38:0)
[00:53:07.790] Timer tick, asking Buildbucket for the build status
[00:53:07.897] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:07.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:39:0) after 1m0s
[00:54:08.028] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:39:0)
[00:54:08.028] Timer tick, asking Buildbucket for the build status
[00:54:08.321] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:08.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:40:0) after 1m0s
[00:55:08.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:40:0)
[00:55:08.597] Timer tick, asking Buildbucket for the build status
[00:55:08.666] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:08.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:41:0) after 1m0s
[00:56:08.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:41:0)
[00:56:08.811] Timer tick, asking Buildbucket for the build status
[00:56:08.952] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:08.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:42:0) after 1m0s
[00:57:08.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:42:0)
[00:57:08.976] Timer tick, asking Buildbucket for the build status
[00:57:09.142] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:09.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:43:0) after 1m0s
[00:58:09.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:43:0)
[00:58:09.208] Timer tick, asking Buildbucket for the build status
[00:58:09.306] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:09.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:44:0) after 1m0s
[00:59:09.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:44:0)
[00:59:09.529] Timer tick, asking Buildbucket for the build status
[00:59:09.624] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:09.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:45:0) after 1m0s
[01:00:09.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:45:0)
[01:00:09.729] Timer tick, asking Buildbucket for the build status
[01:00:09.794] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:09.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:46:0) after 1m0s
[01:01:11.073] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:46:0)
[01:01:11.073] Timer tick, asking Buildbucket for the build status
[01:01:11.195] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:11.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:47:0) after 1m0s
[01:02:11.226] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:47:0)
[01:02:11.226] Timer tick, asking Buildbucket for the build status
[01:02:11.362] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:11.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:48:0) after 1m0s
[01:03:11.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:48:0)
[01:03:11.419] Timer tick, asking Buildbucket for the build status
[01:03:11.557] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:11.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:49:0) after 1m0s
[01:04:11.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:49:0)
[01:04:11.590] Timer tick, asking Buildbucket for the build status
[01:04:11.686] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:11.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:50:0) after 1m0s
[01:05:11.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:50:0)
[01:05:11.781] Timer tick, asking Buildbucket for the build status
[01:05:11.859] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:11.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:51:0) after 1m0s
[01:06:11.943] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:51:0)
[01:06:11.943] Timer tick, asking Buildbucket for the build status
[01:06:12.108] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:12.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:52:0) after 1m0s
[01:07:12.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:52:0)
[01:07:12.130] Timer tick, asking Buildbucket for the build status
[01:07:12.581] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:12.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:53:0) after 1m0s
[01:08:12.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:53:0)
[01:08:12.641] Timer tick, asking Buildbucket for the build status
[01:08:12.739] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:12.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:54:0) after 1m0s
[01:09:12.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:54:0)
[01:09:12.741] Timer tick, asking Buildbucket for the build status
[01:09:12.903] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:12.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:55:0) after 1m0s
[01:10:12.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:55:0)
[01:10:12.985] Timer tick, asking Buildbucket for the build status
[01:10:13.125] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:13.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:56:0) after 1m0s
[01:11:13.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:56:0)
[01:11:13.195] Timer tick, asking Buildbucket for the build status
[01:11:13.287] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:13.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:57:0) after 1m0s
[01:12:13.330] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:57:0)
[01:12:13.330] Timer tick, asking Buildbucket for the build status
[01:12:13.770] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:13.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:58:0) after 1m0s
[01:13:13.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:58:0)
[01:13:13.886] Timer tick, asking Buildbucket for the build status
[01:13:14.077] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:14.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:59:0) after 1m0s
[01:14:14.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:59:0)
[01:14:14.118] Timer tick, asking Buildbucket for the build status
[01:14:14.207] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:14.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:60:0) after 1m0s
[01:15:14.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:60:0)
[01:15:14.304] Timer tick, asking Buildbucket for the build status
[01:15:14.385] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:14.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:61:0) after 1m0s
[01:16:14.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:61:0)
[01:16:14.525] Timer tick, asking Buildbucket for the build status
[01:16:14.598] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:14.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:62:0) after 1m0s
[01:17:14.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:62:0)
[01:17:14.655] Timer tick, asking Buildbucket for the build status
[01:17:14.929] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:14.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:63:0) after 1m0s
[01:18:15.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:63:0)
[01:18:15.042] Timer tick, asking Buildbucket for the build status
[01:18:15.268] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:15.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:64:0) after 1m0s
[01:19:15.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:64:0)
[01:19:15.358] Timer tick, asking Buildbucket for the build status
[01:19:15.433] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:15.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:65:0) after 1m0s
[01:20:15.792] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:65:0)
[01:20:15.792] Timer tick, asking Buildbucket for the build status
[01:20:15.875] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:15.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:66:0) after 1m0s
[01:21:16.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:66:0)
[01:21:16.009] Timer tick, asking Buildbucket for the build status
[01:21:16.147] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:16.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:67:0) after 1m0s
[01:22:16.276] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:67:0)
[01:22:16.276] Timer tick, asking Buildbucket for the build status
[01:22:16.382] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:16.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:68:0) after 1m0s
[01:23:16.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:68:0)
[01:23:16.482] Timer tick, asking Buildbucket for the build status
[01:23:16.543] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:16.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:69:0) after 1m0s
[01:24:16.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:69:0)
[01:24:16.825] Timer tick, asking Buildbucket for the build status
[01:24:16.996] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:16.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:70:0) after 1m0s
[01:25:17.137] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:70:0)
[01:25:17.137] Timer tick, asking Buildbucket for the build status
[01:25:17.310] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:17.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:71:0) after 1m0s
[01:26:17.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:71:0)
[01:26:17.528] Timer tick, asking Buildbucket for the build status
[01:26:17.792] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:17.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:72:0) after 1m0s
[01:27:17.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:72:0)
[01:27:17.940] Timer tick, asking Buildbucket for the build status
[01:27:18.112] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:18.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:73:0) after 1m0s
[01:28:18.198] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:73:0)
[01:28:18.198] Timer tick, asking Buildbucket for the build status
[01:28:18.390] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:18.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:74:0) after 1m0s
[01:29:18.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:74:0)
[01:29:18.575] Timer tick, asking Buildbucket for the build status
[01:29:18.670] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:18.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:75:0) after 1m0s
[01:30:18.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:75:0)
[01:30:18.920] Timer tick, asking Buildbucket for the build status
[01:30:19.014] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:19.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:76:0) after 1m0s
[01:31:19.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:76:0)
[01:31:19.195] Timer tick, asking Buildbucket for the build status
[01:31:19.487] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:19.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:77:0) after 1m0s
[01:32:19.714] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:77:0)
[01:32:19.714] Timer tick, asking Buildbucket for the build status
[01:32:20.855] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:20.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:78:0) after 1m0s
[01:33:21.048] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:78:0)
[01:33:21.048] Timer tick, asking Buildbucket for the build status
[01:33:21.136] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:21.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:79:0) after 1m0s
[01:34:21.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:79:0)
[01:34:21.384] Timer tick, asking Buildbucket for the build status
[01:34:21.441] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:21.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:80:0) after 1m0s
[01:35:21.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:80:0)
[01:35:21.645] Timer tick, asking Buildbucket for the build status
[01:35:21.737] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:21.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:81:0) after 1m0s
[01:36:21.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:81:0)
[01:36:21.970] Timer tick, asking Buildbucket for the build status
[01:36:22.062] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:22.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:82:0) after 1m0s
[01:37:22.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:82:0)
[01:37:22.522] Timer tick, asking Buildbucket for the build status
[01:37:22.720] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:22.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:83:0) after 1m0s
[01:38:22.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:83:0)
[01:38:22.861] Timer tick, asking Buildbucket for the build status
[01:38:22.950] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:22.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:84:0) after 1m0s
[01:39:23.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:84:0)
[01:39:23.042] Timer tick, asking Buildbucket for the build status
[01:39:23.124] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:23.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:85:0) after 1m0s
[01:40:23.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:85:0)
[01:40:23.293] Timer tick, asking Buildbucket for the build status
[01:40:23.360] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:23.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:86:0) after 1m0s
[01:41:23.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:86:0)
[01:41:23.357] Timer tick, asking Buildbucket for the build status
[01:41:23.427] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:23.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:87:0) after 1m0s
[01:42:23.478] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:87:0)
[01:42:23.478] Timer tick, asking Buildbucket for the build status
[01:42:23.603] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:23.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:88:0) after 1m0s
[01:43:23.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:88:0)
[01:43:23.808] Timer tick, asking Buildbucket for the build status
[01:43:25.991] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:25.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:89:0) after 1m0s
[01:44:26.048] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:89:0)
[01:44:26.048] Timer tick, asking Buildbucket for the build status
[01:44:27.238] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:27.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:90:0) after 1m0s
[01:45:27.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:90:0)
[01:45:27.263] Timer tick, asking Buildbucket for the build status
[01:45:27.495] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:27.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:91:0) after 1m0s
[01:46:27.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:91:0)
[01:46:27.519] Timer tick, asking Buildbucket for the build status
[01:46:28.253] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:28.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:92:0) after 1m0s
[01:47:28.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:92:0)
[01:47:28.316] Timer tick, asking Buildbucket for the build status
[01:47:31.968] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:31.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:93:0) after 1m0s
[01:48:32.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:93:0)
[01:48:32.036] Timer tick, asking Buildbucket for the build status
[01:48:32.128] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:32.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:94:0) after 1m0s
[01:49:32.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:94:0)
[01:49:32.209] Timer tick, asking Buildbucket for the build status
[01:49:32.594] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:32.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:95:0) after 1m0s
[01:50:32.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:95:0)
[01:50:32.700] Timer tick, asking Buildbucket for the build status
[01:50:32.824] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:32.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:96:0) after 1m0s
[01:51:32.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:96:0)
[01:51:32.861] Timer tick, asking Buildbucket for the build status
[01:51:32.953] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:32.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:97:0) after 1m0s
[01:52:33.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:97:0)
[01:52:33.067] Timer tick, asking Buildbucket for the build status
[01:52:33.189] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:33.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:98:0) after 1m0s
[01:53:33.285] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:98:0)
[01:53:33.285] Timer tick, asking Buildbucket for the build status
[01:53:33.351] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:33.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:99:0) after 1m0s
[01:54:33.494] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:99:0)
[01:54:33.494] Timer tick, asking Buildbucket for the build status
[01:54:33.578] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:33.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:100:0) after 1m0s
[01:55:33.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:100:0)
[01:55:33.656] Timer tick, asking Buildbucket for the build status
[01:55:33.777] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:33.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:101:0) after 1m0s
[01:56:33.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:101:0)
[01:56:33.823] Timer tick, asking Buildbucket for the build status
[01:56:33.930] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:33.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:102:0) after 1m0s
[01:57:34.070] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:102:0)
[01:57:34.070] Timer tick, asking Buildbucket for the build status
[01:57:34.301] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:34.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:103:0) after 1m0s
[01:58:34.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:103:0)
[01:58:34.444] Timer tick, asking Buildbucket for the build status
[01:58:34.658] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:34.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:104:0) after 1m0s
[01:59:34.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:104:0)
[01:59:34.732] Timer tick, asking Buildbucket for the build status
[01:59:35.033] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:35.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:105:0) after 1m0s
[02:00:35.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:105:0)
[02:00:35.058] Timer tick, asking Buildbucket for the build status
[02:00:35.188] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:35.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:106:0) after 1m0s
[02:01:35.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:106:0)
[02:01:35.237] Timer tick, asking Buildbucket for the build status
[02:01:35.884] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:35.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:107:0) after 1m0s
[02:02:36.070] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:107:0)
[02:02:36.070] Timer tick, asking Buildbucket for the build status
[02:02:36.194] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:36.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:108:0) after 1m0s
[02:03:36.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:108:0)
[02:03:36.293] Timer tick, asking Buildbucket for the build status
[02:03:36.435] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:36.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:109:0) after 1m0s
[02:04:36.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:109:0)
[02:04:36.567] Timer tick, asking Buildbucket for the build status
[02:04:36.646] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:36.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:110:0) after 1m0s
[02:05:36.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:110:0)
[02:05:36.820] Timer tick, asking Buildbucket for the build status
[02:05:37.016] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:37.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:111:0) after 1m0s
[02:06:37.089] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:111:0)
[02:06:37.089] Timer tick, asking Buildbucket for the build status
[02:06:37.197] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:37.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:112:0) after 1m0s
[02:07:37.320] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:112:0)
[02:07:37.320] Timer tick, asking Buildbucket for the build status
[02:07:37.490] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:37.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:113:0) after 1m0s
[02:08:37.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:113:0)
[02:08:37.542] Timer tick, asking Buildbucket for the build status
[02:08:37.631] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:37.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:114:0) after 1m0s
[02:09:37.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:114:0)
[02:09:37.658] Timer tick, asking Buildbucket for the build status
[02:09:37.722] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:37.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:115:0) after 1m0s
[02:10:37.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:115:0)
[02:10:37.808] Timer tick, asking Buildbucket for the build status
[02:10:38.022] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:38.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:116:0) after 1m0s
[02:11:38.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:116:0)
[02:11:38.068] Timer tick, asking Buildbucket for the build status
[02:11:38.172] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:38.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:117:0) after 1m0s
[02:12:38.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:117:0)
[02:12:38.318] Timer tick, asking Buildbucket for the build status
[02:12:38.439] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:38.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:118:0) after 1m0s
[02:13:38.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:118:0)
[02:13:38.585] Timer tick, asking Buildbucket for the build status
[02:13:38.872] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:38.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:119:0) after 1m0s
[02:14:38.979] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:119:0)
[02:14:38.980] Timer tick, asking Buildbucket for the build status
[02:14:39.477] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:39.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:120:0) after 1m0s
[02:15:39.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:120:0)
[02:15:39.505] Timer tick, asking Buildbucket for the build status
[02:15:39.596] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:39.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:121:0) after 1m0s
[02:16:39.725] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:121:0)
[02:16:39.725] Timer tick, asking Buildbucket for the build status
[02:16:39.788] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:39.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:122:0) after 1m0s
[02:17:39.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:122:0)
[02:17:39.856] Timer tick, asking Buildbucket for the build status
[02:17:40.410] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:40.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:123:0) after 1m0s
[02:18:40.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:123:0)
[02:18:40.483] Timer tick, asking Buildbucket for the build status
[02:18:40.656] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:40.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:124:0) after 1m0s
[02:19:40.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:124:0)
[02:19:40.687] Timer tick, asking Buildbucket for the build status
[02:19:40.802] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:40.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:125:0) after 1m0s
[02:20:40.831] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:125:0)
[02:20:40.831] Timer tick, asking Buildbucket for the build status
[02:20:40.915] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:40.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:126:0) after 1m0s
[02:21:41.001] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:126:0)
[02:21:41.001] Timer tick, asking Buildbucket for the build status
[02:21:41.059] Build 8873757279913096800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:41.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039187342260428176:127:0) after 1m0s
[02:21:42.746] Received PubSub notification, asking Buildbucket for the build status
[02:21:42.820] Build 8873757279913096800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:21:42.820] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1595730101830779",
"created_by": "project:chromium",
"created_ts": "1595722614331448",
"id": "8873757279913096800",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cb64bcc45e898b9e7cb5db28e23acb56e422132a\"}}",
"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-2-n03a\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 175, \"got_angle_revision\": \"43b951c8d8e2e6ff69823f96d6b1fe0840716557\", \"got_dawn_revision\": \"d5a4775cfc77da29870286a217ea49e4ab3270a3\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"cb64bcc45e898b9e7cb5db28e23acb56e422132a\", \"got_revision_cp\": \"refs/heads/master@{#791565}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"6a8a74986c357b0c6fa0dfd2b4b9230af8d39d1a\", \"got_v8_revision\": \"5cdec88a9e3e230528eeb9c34c68437a24db4b89\", \"got_v8_revision_cp\": \"refs/heads/8.6.230@{#1}\", \"got_webrtc_revision\": \"82e85676c3b35e721e8e61a1d089f4a0eca4750c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31782}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cb64bcc45e898b9e7cb5db28e23acb56e422132a\"}, \"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-2-n03a\"], \"image\": [\"chrome-win10-1703-20071600-faa8609efbc\"], \"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\": [\"5425-e4e3059\"], \"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": "1595722628294634",
"status": "COMPLETED",
"status_changed_ts": "1595730102207243",
"tags": [
"build_address:luci.chromium.ci/win-official/175",
"builder:win-official",
"buildset:commit/git/cb64bcc45e898b9e7cb5db28e23acb56e422132a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cb64bcc45e898b9e7cb5db28e23acb56e422132a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039187342260428176",
"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/8873757279913096800/+/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:4da14c519e896710",
"user_agent:luci-scheduler"
],
"updated_ts": "1595730102217012",
"url": "https://ci.chromium.org/b/8873757279913096800",
"utcnow_ts": "1595730102809561"
}
}
[02:21:42.820] Invocation finished in 2h4m50.111407841s with status SUCCEEDED