[20:51:49.023] New invocation is queued and will start shortly
[20:51:50.066] Starting the invocation (attempt 1)
[20:51:50.117] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:51:50.117] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:51:50.117] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035213977105554288",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fee98f46fd5f1c851b6fd502d112988aea1646d5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9035213977105554288",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/fee98f46fd5f1c851b6fd502d112988aea1646d5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fee98f46fd5f1c851b6fd502d112988aea1646d5",
"gitiles_ref:refs/heads/master"
]
}
[20:51:50.858] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599511910227848",
"id": "8869783915179776016",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fee98f46fd5f1c851b6fd502d112988aea1646d5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599511910746520",
"tags": [
"build_address:luci.chromium.ci/win-official/533",
"builder:win-official",
"buildset:commit/git/fee98f46fd5f1c851b6fd502d112988aea1646d5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fee98f46fd5f1c851b6fd502d112988aea1646d5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035213977105554288",
"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/8869783915179776016/+/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": "1599511910746638",
"url": "https://ci.chromium.org/b/8869783915179776016",
"utcnow_ts": "1599511910851660"
}
}
[20:51:50.858] Task URL: https://ci.chromium.org/b/8869783915179776016
[20:51:50.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:2:0) after 1m0s
[20:52:28.163] Received PubSub notification, asking Buildbucket for the build status
[20:52:28.286] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:50.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:2:0)
[20:52:50.968] Timer tick, asking Buildbucket for the build status
[20:52:51.047] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:51.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:4:0) after 1m0s
[20:53:51.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:4:0)
[20:53:51.100] Timer tick, asking Buildbucket for the build status
[20:53:51.294] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:51.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:5:0) after 1m0s
[20:54:51.297] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:5:0)
[20:54:51.297] Timer tick, asking Buildbucket for the build status
[20:54:51.432] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:51.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:6:0) after 1m0s
[20:55:51.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:6:0)
[20:55:51.498] Timer tick, asking Buildbucket for the build status
[20:55:51.581] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:51.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:7:0) after 1m0s
[20:56:51.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:7:0)
[20:56:51.697] Timer tick, asking Buildbucket for the build status
[20:56:51.898] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:51.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:8:0) after 1m0s
[20:57:52.032] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:8:0)
[20:57:52.032] Timer tick, asking Buildbucket for the build status
[20:57:52.122] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:52.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:9:0) after 1m0s
[20:58:52.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:9:0)
[20:58:52.238] Timer tick, asking Buildbucket for the build status
[20:58:52.451] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:52.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:10:0) after 1m0s
[20:59:52.494] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:10:0)
[20:59:52.494] Timer tick, asking Buildbucket for the build status
[20:59:52.609] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:52.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:11:0) after 1m0s
[21:00:53.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:11:0)
[21:00:53.052] Timer tick, asking Buildbucket for the build status
[21:00:53.156] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:53.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:12:0) after 1m0s
[21:01:53.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:12:0)
[21:01:53.366] Timer tick, asking Buildbucket for the build status
[21:01:53.503] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:53.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:13:0) after 1m0s
[21:02:53.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:13:0)
[21:02:53.622] Timer tick, asking Buildbucket for the build status
[21:02:53.800] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:53.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:14:0) after 1m0s
[21:03:53.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:14:0)
[21:03:53.877] Timer tick, asking Buildbucket for the build status
[21:03:53.965] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:53.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:15:0) after 1m0s
[21:04:53.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:15:0)
[21:04:53.978] Timer tick, asking Buildbucket for the build status
[21:04:54.098] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:54.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:16:0) after 1m0s
[21:05:54.120] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:16:0)
[21:05:54.120] Timer tick, asking Buildbucket for the build status
[21:05:57.516] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:57.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:17:0) after 1m0s
[21:06:57.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:17:0)
[21:06:57.567] Timer tick, asking Buildbucket for the build status
[21:06:57.700] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:57.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:18:0) after 1m0s
[21:07:57.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:18:0)
[21:07:57.707] Timer tick, asking Buildbucket for the build status
[21:07:57.833] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:57.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:19:0) after 1m0s
[21:08:57.866] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:19:0)
[21:08:57.866] Timer tick, asking Buildbucket for the build status
[21:08:58.010] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:58.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:20:0) after 1m0s
[21:09:58.089] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:20:0)
[21:09:58.089] Timer tick, asking Buildbucket for the build status
[21:09:58.191] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:58.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:21:0) after 1m0s
[21:10:58.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:21:0)
[21:10:58.259] Timer tick, asking Buildbucket for the build status
[21:10:58.396] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:58.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:22:0) after 1m0s
[21:11:58.441] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:22:0)
[21:11:58.441] Timer tick, asking Buildbucket for the build status
[21:11:58.801] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:58.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:23:0) after 1m0s
[21:12:58.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:23:0)
[21:12:58.828] Timer tick, asking Buildbucket for the build status
[21:12:58.911] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:58.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:24:0) after 1m0s
[21:13:58.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:24:0)
[21:13:58.957] Timer tick, asking Buildbucket for the build status
[21:14:01.893] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:01.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:25:0) after 1m0s
[21:15:02.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:25:0)
[21:15:02.682] Timer tick, asking Buildbucket for the build status
[21:15:02.826] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:02.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:26:0) after 1m0s
[21:16:02.860] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:26:0)
[21:16:02.860] Timer tick, asking Buildbucket for the build status
[21:16:02.931] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:02.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:27:0) after 1m0s
[21:17:02.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:27:0)
[21:17:02.955] Timer tick, asking Buildbucket for the build status
[21:17:03.319] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:03.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:28:0) after 1m0s
[21:18:03.338] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:28:0)
[21:18:03.338] Timer tick, asking Buildbucket for the build status
[21:18:03.414] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:03.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:29:0) after 1m0s
[21:19:03.441] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:29:0)
[21:19:03.441] Timer tick, asking Buildbucket for the build status
[21:19:03.576] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:03.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:30:0) after 1m0s
[21:20:03.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:30:0)
[21:20:03.604] Timer tick, asking Buildbucket for the build status
[21:20:04.073] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:04.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:31:0) after 1m0s
[21:21:04.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:31:0)
[21:21:04.199] Timer tick, asking Buildbucket for the build status
[21:21:04.326] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:04.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:32:0) after 1m0s
[21:22:04.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:32:0)
[21:22:04.378] Timer tick, asking Buildbucket for the build status
[21:22:04.817] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:04.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:33:0) after 1m0s
[21:23:05.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:33:0)
[21:23:05.401] Timer tick, asking Buildbucket for the build status
[21:23:05.549] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:05.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:34:0) after 1m0s
[21:24:05.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:34:0)
[21:24:05.593] Timer tick, asking Buildbucket for the build status
[21:24:05.700] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:05.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:35:0) after 1m0s
[21:25:05.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:35:0)
[21:25:05.728] Timer tick, asking Buildbucket for the build status
[21:25:05.821] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:05.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:36:0) after 1m0s
[21:26:05.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:36:0)
[21:26:05.933] Timer tick, asking Buildbucket for the build status
[21:26:06.034] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:06.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:37:0) after 1m0s
[21:27:06.054] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:37:0)
[21:27:06.054] Timer tick, asking Buildbucket for the build status
[21:27:06.189] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:06.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:38:0) after 1m0s
[21:28:06.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:38:0)
[21:28:06.228] Timer tick, asking Buildbucket for the build status
[21:28:06.351] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:06.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:39:0) after 1m0s
[21:29:06.377] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:39:0)
[21:29:06.377] Timer tick, asking Buildbucket for the build status
[21:29:07.164] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:07.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:40:0) after 1m0s
[21:30:07.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:40:0)
[21:30:07.292] Timer tick, asking Buildbucket for the build status
[21:30:07.352] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:07.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:41:0) after 1m0s
[21:31:07.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:41:0)
[21:31:07.392] Timer tick, asking Buildbucket for the build status
[21:31:07.466] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:07.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:42:0) after 1m0s
[21:32:07.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:42:0)
[21:32:07.531] Timer tick, asking Buildbucket for the build status
[21:32:07.661] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:07.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:43:0) after 1m0s
[21:33:07.706] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:43:0)
[21:33:07.706] Timer tick, asking Buildbucket for the build status
[21:33:07.771] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:07.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:44:0) after 1m0s
[21:34:07.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:44:0)
[21:34:07.955] Timer tick, asking Buildbucket for the build status
[21:34:08.287] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:08.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:45:0) after 1m0s
[21:35:08.365] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:45:0)
[21:35:08.365] Timer tick, asking Buildbucket for the build status
[21:35:08.674] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:08.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:46:0) after 1m0s
[21:36:08.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:46:0)
[21:36:08.746] Timer tick, asking Buildbucket for the build status
[21:36:09.142] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:09.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:47:0) after 1m0s
[21:37:09.166] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:47:0)
[21:37:09.166] Timer tick, asking Buildbucket for the build status
[21:37:09.494] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:09.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:48:0) after 1m0s
[21:38:09.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:48:0)
[21:38:09.626] Timer tick, asking Buildbucket for the build status
[21:38:09.726] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:09.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:49:0) after 1m0s
[21:39:09.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:49:0)
[21:39:09.872] Timer tick, asking Buildbucket for the build status
[21:39:12.251] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:12.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:50:0) after 1m0s
[21:40:12.334] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:50:0)
[21:40:12.334] Timer tick, asking Buildbucket for the build status
[21:40:12.422] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:12.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:51:0) after 1m0s
[21:41:12.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:51:0)
[21:41:12.538] Timer tick, asking Buildbucket for the build status
[21:41:12.639] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:12.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:52:0) after 1m0s
[21:42:12.670] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:52:0)
[21:42:12.670] Timer tick, asking Buildbucket for the build status
[21:42:12.783] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:12.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:53:0) after 1m0s
[21:43:13.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:53:0)
[21:43:13.025] Timer tick, asking Buildbucket for the build status
[21:43:13.192] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:13.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:54:0) after 1m0s
[21:44:13.493] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:54:0)
[21:44:13.493] Timer tick, asking Buildbucket for the build status
[21:44:13.561] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:13.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:55:0) after 1m0s
[21:45:13.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:55:0)
[21:45:13.592] Timer tick, asking Buildbucket for the build status
[21:45:13.664] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:13.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:56:0) after 1m0s
[21:46:13.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:56:0)
[21:46:13.723] Timer tick, asking Buildbucket for the build status
[21:46:13.819] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:13.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:57:0) after 1m0s
[21:47:13.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:57:0)
[21:47:13.839] Timer tick, asking Buildbucket for the build status
[21:47:14.138] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:14.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:58:0) after 1m0s
[21:48:14.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:58:0)
[21:48:14.201] Timer tick, asking Buildbucket for the build status
[21:48:14.286] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:14.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:59:0) after 1m0s
[21:49:14.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:59:0)
[21:49:14.317] Timer tick, asking Buildbucket for the build status
[21:49:14.530] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:14.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:60:0) after 1m0s
[21:50:14.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:60:0)
[21:50:14.556] Timer tick, asking Buildbucket for the build status
[21:50:14.937] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:14.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:61:0) after 1m0s
[21:51:14.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:61:0)
[21:51:14.976] Timer tick, asking Buildbucket for the build status
[21:51:15.049] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:15.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:62:0) after 1m0s
[21:52:15.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:62:0)
[21:52:15.141] Timer tick, asking Buildbucket for the build status
[21:52:15.269] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:15.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:63:0) after 1m0s
[21:53:15.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:63:0)
[21:53:15.304] Timer tick, asking Buildbucket for the build status
[21:53:15.488] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:15.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:64:0) after 1m0s
[21:54:15.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:64:0)
[21:54:15.562] Timer tick, asking Buildbucket for the build status
[21:54:15.632] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:15.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:65:0) after 1m0s
[21:55:15.703] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:65:0)
[21:55:15.704] Timer tick, asking Buildbucket for the build status
[21:55:15.785] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:15.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:66:0) after 1m0s
[21:56:15.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:66:0)
[21:56:15.820] Timer tick, asking Buildbucket for the build status
[21:56:15.929] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:15.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:67:0) after 1m0s
[21:57:16.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:67:0)
[21:57:16.035] Timer tick, asking Buildbucket for the build status
[21:57:16.139] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:16.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:68:0) after 1m0s
[21:58:16.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:68:0)
[21:58:16.553] Timer tick, asking Buildbucket for the build status
[21:58:16.704] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:16.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:69:0) after 1m0s
[21:59:16.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:69:0)
[21:59:16.726] Timer tick, asking Buildbucket for the build status
[21:59:16.937] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:16.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:70:0) after 1m0s
[22:00:17.014] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:70:0)
[22:00:17.014] Timer tick, asking Buildbucket for the build status
[22:00:17.084] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:17.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:71:0) after 1m0s
[22:01:17.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:71:0)
[22:01:17.118] Timer tick, asking Buildbucket for the build status
[22:01:17.193] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:17.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:72:0) after 1m0s
[22:02:17.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:72:0)
[22:02:17.850] Timer tick, asking Buildbucket for the build status
[22:02:18.014] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:18.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:73:0) after 1m0s
[22:03:18.473] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:73:0)
[22:03:18.473] Timer tick, asking Buildbucket for the build status
[22:03:18.551] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:18.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:74:0) after 1m0s
[22:04:18.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:74:0)
[22:04:18.639] Timer tick, asking Buildbucket for the build status
[22:04:18.716] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:18.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:75:0) after 1m0s
[22:05:19.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:75:0)
[22:05:19.104] Timer tick, asking Buildbucket for the build status
[22:05:19.191] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:19.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:76:0) after 1m0s
[22:06:19.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:76:0)
[22:06:19.241] Timer tick, asking Buildbucket for the build status
[22:06:19.352] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:19.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:77:0) after 1m0s
[22:07:19.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:77:0)
[22:07:19.641] Timer tick, asking Buildbucket for the build status
[22:07:19.762] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:19.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:78:0) after 1m0s
[22:08:19.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:78:0)
[22:08:19.876] Timer tick, asking Buildbucket for the build status
[22:08:19.996] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:19.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:79:0) after 1m0s
[22:09:20.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:79:0)
[22:09:20.012] Timer tick, asking Buildbucket for the build status
[22:09:20.084] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:20.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:80:0) after 1m0s
[22:10:20.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:80:0)
[22:10:20.125] Timer tick, asking Buildbucket for the build status
[22:10:20.196] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:20.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:81:0) after 1m0s
[22:11:20.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:81:0)
[22:11:20.314] Timer tick, asking Buildbucket for the build status
[22:11:20.468] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:20.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:82:0) after 1m0s
[22:12:20.690] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:82:0)
[22:12:20.690] Timer tick, asking Buildbucket for the build status
[22:12:20.885] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:20.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:83:0) after 1m0s
[22:13:20.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:83:0)
[22:13:20.920] Timer tick, asking Buildbucket for the build status
[22:13:21.002] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:21.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:84:0) after 1m0s
[22:14:21.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:84:0)
[22:14:21.192] Timer tick, asking Buildbucket for the build status
[22:14:21.299] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:21.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:85:0) after 1m0s
[22:15:21.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:85:0)
[22:15:21.421] Timer tick, asking Buildbucket for the build status
[22:15:21.725] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:21.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:86:0) after 1m0s
[22:16:21.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:86:0)
[22:16:21.846] Timer tick, asking Buildbucket for the build status
[22:16:22.147] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:22.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:87:0) after 1m0s
[22:17:22.184] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:87:0)
[22:17:22.184] Timer tick, asking Buildbucket for the build status
[22:17:22.727] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:22.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:88:0) after 1m0s
[22:18:22.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:88:0)
[22:18:22.744] Timer tick, asking Buildbucket for the build status
[22:18:22.822] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:22.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:89:0) after 1m0s
[22:19:22.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:89:0)
[22:19:22.873] Timer tick, asking Buildbucket for the build status
[22:19:23.801] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:23.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:90:0) after 1m0s
[22:20:23.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:90:0)
[22:20:23.962] Timer tick, asking Buildbucket for the build status
[22:20:24.048] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:24.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:91:0) after 1m0s
[22:21:24.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:91:0)
[22:21:24.169] Timer tick, asking Buildbucket for the build status
[22:21:24.234] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:24.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:92:0) after 1m0s
[22:22:24.461] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:92:0)
[22:22:24.461] Timer tick, asking Buildbucket for the build status
[22:22:24.642] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:24.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:93:0) after 1m0s
[22:23:24.711] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:93:0)
[22:23:24.711] Timer tick, asking Buildbucket for the build status
[22:23:24.802] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:24.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:94:0) after 1m0s
[22:24:24.929] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:94:0)
[22:24:24.929] Timer tick, asking Buildbucket for the build status
[22:24:25.180] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:25.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:95:0) after 1m0s
[22:25:31.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:95:0)
[22:25:31.784] Timer tick, asking Buildbucket for the build status
[22:25:31.862] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:31.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:96:0) after 1m0s
[22:26:31.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:96:0)
[22:26:31.905] Timer tick, asking Buildbucket for the build status
[22:26:31.962] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:31.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:97:0) after 1m0s
[22:27:32.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:97:0)
[22:27:32.043] Timer tick, asking Buildbucket for the build status
[22:27:32.473] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:32.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:98:0) after 1m0s
[22:28:32.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:98:0)
[22:28:32.571] Timer tick, asking Buildbucket for the build status
[22:28:32.641] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:32.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:99:0) after 1m0s
[22:29:32.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:99:0)
[22:29:32.686] Timer tick, asking Buildbucket for the build status
[22:29:32.763] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:32.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:100:0) after 1m0s
[22:30:32.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:100:0)
[22:30:32.839] Timer tick, asking Buildbucket for the build status
[22:30:33.058] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:33.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:101:0) after 1m0s
[22:31:33.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:101:0)
[22:31:33.141] Timer tick, asking Buildbucket for the build status
[22:31:33.203] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:33.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:102:0) after 1m0s
[22:32:33.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:102:0)
[22:32:33.253] Timer tick, asking Buildbucket for the build status
[22:32:33.694] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:33.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:103:0) after 1m0s
[22:33:33.718] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:103:0)
[22:33:33.718] Timer tick, asking Buildbucket for the build status
[22:33:34.226] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:34.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:104:0) after 1m0s
[22:34:34.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:104:0)
[22:34:34.231] Timer tick, asking Buildbucket for the build status
[22:34:34.496] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:34.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:105:0) after 1m0s
[22:35:34.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:105:0)
[22:35:34.526] Timer tick, asking Buildbucket for the build status
[22:35:34.723] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:34.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:106:0) after 1m0s
[22:36:35.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:106:0)
[22:36:35.009] Timer tick, asking Buildbucket for the build status
[22:36:35.105] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:35.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:107:0) after 1m0s
[22:37:35.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:107:0)
[22:37:35.199] Timer tick, asking Buildbucket for the build status
[22:37:35.319] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:35.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:108:0) after 1m0s
[22:38:35.341] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:108:0)
[22:38:35.341] Timer tick, asking Buildbucket for the build status
[22:38:35.413] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:35.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:109:0) after 1m0s
[22:39:35.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:109:0)
[22:39:35.490] Timer tick, asking Buildbucket for the build status
[22:39:35.618] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:35.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:110:0) after 1m0s
[22:40:35.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:110:0)
[22:40:35.633] Timer tick, asking Buildbucket for the build status
[22:40:35.707] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:35.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:111:0) after 1m0s
[22:41:35.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:111:0)
[22:41:35.742] Timer tick, asking Buildbucket for the build status
[22:41:35.808] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:35.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:112:0) after 1m0s
[22:42:35.961] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:112:0)
[22:42:35.961] Timer tick, asking Buildbucket for the build status
[22:42:36.539] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:36.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:113:0) after 1m0s
[22:43:36.709] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:113:0)
[22:43:36.709] Timer tick, asking Buildbucket for the build status
[22:43:37.049] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:37.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:114:0) after 1m0s
[22:44:37.089] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:114:0)
[22:44:37.089] Timer tick, asking Buildbucket for the build status
[22:44:37.190] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:37.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:115:0) after 1m0s
[22:45:37.265] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:115:0)
[22:45:37.265] Timer tick, asking Buildbucket for the build status
[22:45:37.330] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:37.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:116:0) after 1m0s
[22:46:37.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:116:0)
[22:46:37.430] Timer tick, asking Buildbucket for the build status
[22:46:37.489] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:37.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:117:0) after 1m0s
[22:47:37.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:117:0)
[22:47:37.520] Timer tick, asking Buildbucket for the build status
[22:47:37.628] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:37.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:118:0) after 1m0s
[22:48:37.649] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:118:0)
[22:48:37.649] Timer tick, asking Buildbucket for the build status
[22:48:38.095] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:38.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:119:0) after 1m0s
[22:49:38.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:119:0)
[22:49:38.279] Timer tick, asking Buildbucket for the build status
[22:49:38.369] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:38.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:120:0) after 1m0s
[22:50:38.446] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:120:0)
[22:50:38.446] Timer tick, asking Buildbucket for the build status
[22:50:38.524] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:38.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:121:0) after 1m0s
[22:51:38.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:121:0)
[22:51:38.616] Timer tick, asking Buildbucket for the build status
[22:51:38.676] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:38.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:122:0) after 1m0s
[22:52:38.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:122:0)
[22:52:38.774] Timer tick, asking Buildbucket for the build status
[22:52:38.977] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:38.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:123:0) after 1m0s
[22:53:39.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:123:0)
[22:53:39.269] Timer tick, asking Buildbucket for the build status
[22:53:39.377] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:39.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:124:0) after 1m0s
[22:54:39.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:124:0)
[22:54:39.663] Timer tick, asking Buildbucket for the build status
[22:54:40.205] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:40.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:125:0) after 1m0s
[22:55:40.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:125:0)
[22:55:40.460] Timer tick, asking Buildbucket for the build status
[22:55:40.556] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:40.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:126:0) after 1m0s
[22:56:40.675] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:126:0)
[22:56:40.675] Timer tick, asking Buildbucket for the build status
[22:56:40.787] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:40.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:127:0) after 1m0s
[22:57:41.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:127:0)
[22:57:41.069] Timer tick, asking Buildbucket for the build status
[22:57:41.593] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:41.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:128:0) after 1m0s
[22:58:41.625] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:128:0)
[22:58:41.625] Timer tick, asking Buildbucket for the build status
[22:58:41.692] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:41.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:129:0) after 1m0s
[22:59:41.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:129:0)
[22:59:41.721] Timer tick, asking Buildbucket for the build status
[22:59:41.835] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:41.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:130:0) after 1m0s
[23:00:41.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:130:0)
[23:00:41.874] Timer tick, asking Buildbucket for the build status
[23:00:41.944] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:41.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:131:0) after 1m0s
[23:01:41.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:131:0)
[23:01:41.978] Timer tick, asking Buildbucket for the build status
[23:01:44.494] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:44.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:132:0) after 1m0s
[23:02:44.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:132:0)
[23:02:44.820] Timer tick, asking Buildbucket for the build status
[23:02:44.949] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:44.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:133:0) after 1m0s
[23:03:45.014] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:133:0)
[23:03:45.014] Timer tick, asking Buildbucket for the build status
[23:03:45.107] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:45.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:134:0) after 1m0s
[23:04:45.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:134:0)
[23:04:45.145] Timer tick, asking Buildbucket for the build status
[23:04:45.333] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:45.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:135:0) after 1m0s
[23:05:45.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:135:0)
[23:05:45.362] Timer tick, asking Buildbucket for the build status
[23:05:45.680] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:45.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:136:0) after 1m0s
[23:06:45.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:136:0)
[23:06:45.713] Timer tick, asking Buildbucket for the build status
[23:06:45.778] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:45.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:137:0) after 1m0s
[23:07:45.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:137:0)
[23:07:45.845] Timer tick, asking Buildbucket for the build status
[23:07:46.018] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:46.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:138:0) after 1m0s
[23:08:46.194] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:138:0)
[23:08:46.195] Timer tick, asking Buildbucket for the build status
[23:08:46.590] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:46.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:139:0) after 1m0s
[23:09:46.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:139:0)
[23:09:46.620] Timer tick, asking Buildbucket for the build status
[23:09:46.985] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:46.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:140:0) after 1m0s
[23:10:47.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:140:0)
[23:10:47.034] Timer tick, asking Buildbucket for the build status
[23:10:47.100] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:47.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:141:0) after 1m0s
[23:11:47.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:141:0)
[23:11:47.141] Timer tick, asking Buildbucket for the build status
[23:11:47.226] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:47.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:142:0) after 1m0s
[23:12:47.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:142:0)
[23:12:47.481] Timer tick, asking Buildbucket for the build status
[23:12:47.619] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:47.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:143:0) after 1m0s
[23:13:47.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:143:0)
[23:13:47.682] Timer tick, asking Buildbucket for the build status
[23:13:47.786] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:47.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:144:0) after 1m0s
[23:14:47.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:144:0)
[23:14:47.809] Timer tick, asking Buildbucket for the build status
[23:14:47.882] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:47.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:145:0) after 1m0s
[23:15:47.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:145:0)
[23:15:47.909] Timer tick, asking Buildbucket for the build status
[23:15:47.976] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:47.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:146:0) after 1m0s
[23:16:47.998] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:146:0)
[23:16:47.998] Timer tick, asking Buildbucket for the build status
[23:16:48.075] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:48.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:147:0) after 1m0s
[23:17:48.215] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:147:0)
[23:17:48.215] Timer tick, asking Buildbucket for the build status
[23:17:48.341] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:48.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:148:0) after 1m0s
[23:18:48.706] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:148:0)
[23:18:48.706] Timer tick, asking Buildbucket for the build status
[23:18:49.226] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:49.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:149:0) after 1m0s
[23:19:49.373] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:149:0)
[23:19:49.373] Timer tick, asking Buildbucket for the build status
[23:19:49.511] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:49.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:150:0) after 1m0s
[23:20:49.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:150:0)
[23:20:49.602] Timer tick, asking Buildbucket for the build status
[23:20:49.665] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:49.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:151:0) after 1m0s
[23:21:49.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:151:0)
[23:21:49.738] Timer tick, asking Buildbucket for the build status
[23:21:49.814] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:49.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:152:0) after 1m0s
[23:22:49.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:152:0)
[23:22:49.819] Timer tick, asking Buildbucket for the build status
[23:22:49.952] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:49.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:153:0) after 1m0s
[23:23:50.011] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:153:0)
[23:23:50.011] Timer tick, asking Buildbucket for the build status
[23:23:50.119] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:50.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:154:0) after 1m0s
[23:24:50.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:154:0)
[23:24:50.142] Timer tick, asking Buildbucket for the build status
[23:24:50.233] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:50.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:155:0) after 1m0s
[23:25:50.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:155:0)
[23:25:50.254] Timer tick, asking Buildbucket for the build status
[23:25:50.321] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:50.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:156:0) after 1m0s
[23:26:50.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:156:0)
[23:26:50.371] Timer tick, asking Buildbucket for the build status
[23:26:50.448] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:50.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:157:0) after 1m0s
[23:27:50.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:157:0)
[23:27:50.616] Timer tick, asking Buildbucket for the build status
[23:27:50.726] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:50.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:158:0) after 1m0s
[23:28:50.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:158:0)
[23:28:50.757] Timer tick, asking Buildbucket for the build status
[23:28:53.314] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:53.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:159:0) after 1m0s
[23:29:53.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:159:0)
[23:29:53.447] Timer tick, asking Buildbucket for the build status
[23:29:53.540] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:53.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:160:0) after 1m0s
[23:30:53.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:160:0)
[23:30:53.584] Timer tick, asking Buildbucket for the build status
[23:30:53.655] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:53.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:161:0) after 1m0s
[23:31:53.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:161:0)
[23:31:53.682] Timer tick, asking Buildbucket for the build status
[23:31:53.741] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:53.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:162:0) after 1m0s
[23:32:53.771] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:162:0)
[23:32:53.771] Timer tick, asking Buildbucket for the build status
[23:32:53.905] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:53.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:163:0) after 1m0s
[23:33:53.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:163:0)
[23:33:53.933] Timer tick, asking Buildbucket for the build status
[23:33:54.117] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:54.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:164:0) after 1m0s
[23:34:54.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:164:0)
[23:34:54.167] Timer tick, asking Buildbucket for the build status
[23:34:54.287] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:54.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:165:0) after 1m0s
[23:35:54.323] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:165:0)
[23:35:54.323] Timer tick, asking Buildbucket for the build status
[23:35:54.417] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:54.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:166:0) after 1m0s
[23:36:54.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:166:0)
[23:36:54.447] Timer tick, asking Buildbucket for the build status
[23:36:54.618] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:54.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:167:0) after 1m0s
[23:37:54.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:167:0)
[23:37:54.652] Timer tick, asking Buildbucket for the build status
[23:37:54.820] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:54.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:168:0) after 1m0s
[23:38:55.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:168:0)
[23:38:55.167] Timer tick, asking Buildbucket for the build status
[23:38:55.289] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:55.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:169:0) after 1m0s
[23:39:55.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:169:0)
[23:39:55.326] Timer tick, asking Buildbucket for the build status
[23:39:55.675] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:55.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:170:0) after 1m0s
[23:40:55.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:170:0)
[23:40:55.737] Timer tick, asking Buildbucket for the build status
[23:40:55.907] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:55.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:171:0) after 1m0s
[23:41:55.934] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:171:0)
[23:41:55.934] Timer tick, asking Buildbucket for the build status
[23:41:56.188] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:56.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:172:0) after 1m0s
[23:42:56.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:172:0)
[23:42:56.249] Timer tick, asking Buildbucket for the build status
[23:42:56.365] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:56.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:173:0) after 1m0s
[23:43:56.396] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:173:0)
[23:43:56.396] Timer tick, asking Buildbucket for the build status
[23:43:56.505] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:56.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:174:0) after 1m0s
[23:44:56.536] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:174:0)
[23:44:56.536] Timer tick, asking Buildbucket for the build status
[23:44:56.632] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:56.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:175:0) after 1m0s
[23:45:56.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:175:0)
[23:45:56.665] Timer tick, asking Buildbucket for the build status
[23:45:56.727] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:56.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:176:0) after 1m0s
[23:46:56.767] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:176:0)
[23:46:56.767] Timer tick, asking Buildbucket for the build status
[23:46:56.965] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:56.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:177:0) after 1m0s
[23:47:57.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:177:0)
[23:47:57.003] Timer tick, asking Buildbucket for the build status
[23:47:57.110] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:57.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:178:0) after 1m0s
[23:48:57.136] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:178:0)
[23:48:57.136] Timer tick, asking Buildbucket for the build status
[23:48:57.329] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:57.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:179:0) after 1m0s
[23:49:57.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:179:0)
[23:49:57.391] Timer tick, asking Buildbucket for the build status
[23:49:57.592] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:57.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:180:0) after 1m0s
[23:50:57.709] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:180:0)
[23:50:57.709] Timer tick, asking Buildbucket for the build status
[23:50:57.776] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:57.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:181:0) after 1m0s
[23:51:57.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:181:0)
[23:51:57.803] Timer tick, asking Buildbucket for the build status
[23:51:57.939] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:57.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:182:0) after 1m0s
[23:52:57.984] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:182:0)
[23:52:57.984] Timer tick, asking Buildbucket for the build status
[23:52:58.117] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:58.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:183:0) after 1m0s
[23:53:58.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:183:0)
[23:53:58.163] Timer tick, asking Buildbucket for the build status
[23:53:58.422] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:58.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:184:0) after 1m0s
[23:54:58.456] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:184:0)
[23:54:58.456] Timer tick, asking Buildbucket for the build status
[23:54:58.530] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:58.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:185:0) after 1m0s
[23:55:58.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:185:0)
[23:55:58.640] Timer tick, asking Buildbucket for the build status
[23:55:58.823] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:58.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:186:0) after 1m0s
[23:56:58.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:186:0)
[23:56:58.842] Timer tick, asking Buildbucket for the build status
[23:56:58.931] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:58.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:187:0) after 1m0s
[23:57:58.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:187:0)
[23:57:58.968] Timer tick, asking Buildbucket for the build status
[23:57:59.092] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:59.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:188:0) after 1m0s
[23:58:59.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:188:0)
[23:58:59.142] Timer tick, asking Buildbucket for the build status
[23:58:59.216] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:59.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:189:0) after 1m0s
[23:59:59.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:189:0)
[23:59:59.356] Timer tick, asking Buildbucket for the build status
[23:59:59.636] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:59.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:190:0) after 1m0s
[00:00:59.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:190:0)
[00:00:59.677] Timer tick, asking Buildbucket for the build status
[00:00:59.821] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:59.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:191:0) after 1m0s
[00:01:59.853] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:191:0)
[00:01:59.853] Timer tick, asking Buildbucket for the build status
[00:01:59.917] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:59.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:192:0) after 1m0s
[00:03:00.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:192:0)
[00:03:00.235] Timer tick, asking Buildbucket for the build status
[00:03:00.509] Build 8869783915179776016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:00.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035213977105554288:193:0) after 1m0s
[00:03:37.666] Received PubSub notification, asking Buildbucket for the build status
[00:03:37.979] Build 8869783915179776016: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:03:37.979] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599523415970364",
"created_by": "project:chromium",
"created_ts": "1599511910227848",
"id": "8869783915179776016",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fee98f46fd5f1c851b6fd502d112988aea1646d5\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"fee98f46fd5f1c851b6fd502d112988aea1646d5\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-8-0oxp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 533, \"got_angle_revision\": \"07d44b97cf6511e0affba380d724aa6f441dada8\", \"got_dawn_revision\": \"62442aceb0fb8af0c1edd07b5f18b4ec181b8fbf\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"fee98f46fd5f1c851b6fd502d112988aea1646d5\", \"got_revision_cp\": \"refs/heads/master@{#804801}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"10121443c16deb0435be8ea61be54e3bee1b9855\", \"got_v8_revision\": \"3e2d6551eed76b7bdafb278b44c630965a840da8\", \"got_v8_revision_cp\": \"refs/heads/8.7.75@{#1}\", \"got_webrtc_revision\": \"9e9c8b7155d49c2f38b82fe9c9707181c57073c9\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32051}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fee98f46fd5f1c851b6fd502d112988aea1646d5\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-0oxp\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"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\": [\"5511-73542fe\"], \"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": "1599511947363332",
"status": "COMPLETED",
"status_changed_ts": "1599523416954994",
"tags": [
"build_address:luci.chromium.ci/win-official/533",
"builder:win-official",
"buildset:commit/git/fee98f46fd5f1c851b6fd502d112988aea1646d5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fee98f46fd5f1c851b6fd502d112988aea1646d5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035213977105554288",
"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/8869783915179776016/+/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:4e8328621a0f4a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1599523417077019",
"url": "https://ci.chromium.org/b/8869783915179776016",
"utcnow_ts": "1599523417920900"
}
}
[00:03:37.980] Invocation finished in 3h11m48.972975409s with status SUCCEEDED