[03:02:11.403] New invocation is queued and will start shortly
[03:02:12.743] Starting the invocation (attempt 1)
[03:02:12.794] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:02:12.794] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:02:12.794] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9032291572329909232",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"09d89563a118e1bb0987f27d2fd0738774f1b59e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9032291572329909232",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/09d89563a118e1bb0987f27d2fd0738774f1b59e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09d89563a118e1bb0987f27d2fd0738774f1b59e",
"gitiles_ref:refs/heads/master"
]
}
[03:02:15.295] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1602298933310679",
"id": "8866861509663967520",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"09d89563a118e1bb0987f27d2fd0738774f1b59e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602298934869044",
"tags": [
"build_address:luci.chromium.ci/win-official/780",
"builder:win-official",
"buildset:commit/git/09d89563a118e1bb0987f27d2fd0738774f1b59e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09d89563a118e1bb0987f27d2fd0738774f1b59e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032291572329909232",
"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/8866861509663967520/+/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": "1602298934869219",
"url": "https://ci.chromium.org/b/8866861509663967520",
"utcnow_ts": "1602298935234366"
}
}
[03:02:15.295] Task URL: https://ci.chromium.org/b/8866861509663967520
[03:02:15.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:2:0) after 1m0s
[03:02:51.148] Received PubSub notification, asking Buildbucket for the build status
[03:02:51.398] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:15.355] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:2:0)
[03:03:15.355] Timer tick, asking Buildbucket for the build status
[03:03:15.606] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:15.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:4:0) after 1m0s
[03:04:15.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:4:0)
[03:04:15.633] Timer tick, asking Buildbucket for the build status
[03:04:15.833] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:15.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:5:0) after 1m0s
[03:05:15.862] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:5:0)
[03:05:15.862] Timer tick, asking Buildbucket for the build status
[03:05:15.971] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:15.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:6:0) after 1m0s
[03:06:16.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:6:0)
[03:06:16.105] Timer tick, asking Buildbucket for the build status
[03:06:16.401] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:16.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:7:0) after 1m0s
[03:07:16.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:7:0)
[03:07:16.593] Timer tick, asking Buildbucket for the build status
[03:07:17.001] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:17.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:8:0) after 1m0s
[03:08:17.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:8:0)
[03:08:17.029] Timer tick, asking Buildbucket for the build status
[03:08:17.416] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:17.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:9:0) after 1m0s
[03:09:17.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:9:0)
[03:09:17.471] Timer tick, asking Buildbucket for the build status
[03:09:17.562] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:17.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:10:0) after 1m0s
[03:10:17.628] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:10:0)
[03:10:17.628] Timer tick, asking Buildbucket for the build status
[03:10:17.734] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:17.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:11:0) after 1m0s
[03:11:17.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:11:0)
[03:11:17.808] Timer tick, asking Buildbucket for the build status
[03:11:17.897] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:17.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:12:0) after 1m0s
[03:12:17.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:12:0)
[03:12:17.925] Timer tick, asking Buildbucket for the build status
[03:12:18.075] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:18.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:13:0) after 1m0s
[03:13:18.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:13:0)
[03:13:18.102] Timer tick, asking Buildbucket for the build status
[03:13:18.181] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:18.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:14:0) after 1m0s
[03:14:18.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:14:0)
[03:14:18.283] Timer tick, asking Buildbucket for the build status
[03:14:18.564] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:18.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:15:0) after 1m0s
[03:15:18.624] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:15:0)
[03:15:18.624] Timer tick, asking Buildbucket for the build status
[03:15:18.690] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:18.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:16:0) after 1m0s
[03:16:18.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:16:0)
[03:16:18.798] Timer tick, asking Buildbucket for the build status
[03:16:18.850] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:18.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:17:0) after 1m0s
[03:17:19.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:17:0)
[03:17:19.058] Timer tick, asking Buildbucket for the build status
[03:17:19.264] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:19.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:18:0) after 1m0s
[03:18:19.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:18:0)
[03:18:19.275] Timer tick, asking Buildbucket for the build status
[03:18:19.347] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:19.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:19:0) after 1m0s
[03:19:19.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:19:0)
[03:19:19.339] Timer tick, asking Buildbucket for the build status
[03:19:19.397] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:19.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:20:0) after 1m0s
[03:20:19.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:20:0)
[03:20:19.521] Timer tick, asking Buildbucket for the build status
[03:20:19.842] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:19.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:21:0) after 1m0s
[03:21:19.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:21:0)
[03:21:19.933] Timer tick, asking Buildbucket for the build status
[03:21:20.115] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:20.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:22:0) after 1m0s
[03:22:20.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:22:0)
[03:22:20.267] Timer tick, asking Buildbucket for the build status
[03:22:20.380] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:20.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:23:0) after 1m0s
[03:23:20.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:23:0)
[03:23:20.601] Timer tick, asking Buildbucket for the build status
[03:23:20.708] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:20.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:24:0) after 1m0s
[03:24:20.776] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:24:0)
[03:24:20.776] Timer tick, asking Buildbucket for the build status
[03:24:20.833] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:20.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:25:0) after 1m0s
[03:25:20.916] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:25:0)
[03:25:20.916] Timer tick, asking Buildbucket for the build status
[03:25:21.472] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:21.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:26:0) after 1m0s
[03:26:21.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:26:0)
[03:26:21.623] Timer tick, asking Buildbucket for the build status
[03:26:21.672] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:21.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:27:0) after 1m0s
[03:27:21.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:27:0)
[03:27:21.814] Timer tick, asking Buildbucket for the build status
[03:27:22.800] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:22.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:28:0) after 1m0s
[03:28:22.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:28:0)
[03:28:22.905] Timer tick, asking Buildbucket for the build status
[03:28:23.010] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:23.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:29:0) after 1m0s
[03:29:23.040] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:29:0)
[03:29:23.040] Timer tick, asking Buildbucket for the build status
[03:29:23.102] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:23.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:30:0) after 1m0s
[03:30:23.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:30:0)
[03:30:23.227] Timer tick, asking Buildbucket for the build status
[03:30:23.290] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:23.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:31:0) after 1m0s
[03:31:23.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:31:0)
[03:31:23.317] Timer tick, asking Buildbucket for the build status
[03:31:24.907] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:24.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:32:0) after 1m0s
[03:32:24.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:32:0)
[03:32:24.926] Timer tick, asking Buildbucket for the build status
[03:32:25.099] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:25.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:33:0) after 1m0s
[03:33:25.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:33:0)
[03:33:25.167] Timer tick, asking Buildbucket for the build status
[03:33:25.291] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:25.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:34:0) after 1m0s
[03:34:25.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:34:0)
[03:34:25.367] Timer tick, asking Buildbucket for the build status
[03:34:25.606] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:25.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:35:0) after 1m0s
[03:35:25.609] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:35:0)
[03:35:25.609] Timer tick, asking Buildbucket for the build status
[03:35:25.702] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:25.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:36:0) after 1m0s
[03:36:25.791] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:36:0)
[03:36:25.791] Timer tick, asking Buildbucket for the build status
[03:36:25.942] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:25.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:37:0) after 1m0s
[03:37:26.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:37:0)
[03:37:26.017] Timer tick, asking Buildbucket for the build status
[03:37:26.396] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:26.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:38:0) after 1m0s
[03:38:26.426] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:38:0)
[03:38:26.426] Timer tick, asking Buildbucket for the build status
[03:38:26.480] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:26.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:39:0) after 1m0s
[03:39:26.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:39:0)
[03:39:26.535] Timer tick, asking Buildbucket for the build status
[03:39:26.595] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:26.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:40:0) after 1m0s
[03:40:26.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:40:0)
[03:40:26.653] Timer tick, asking Buildbucket for the build status
[03:40:26.812] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:26.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:41:0) after 1m0s
[03:41:26.812] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:41:0)
[03:41:26.812] Timer tick, asking Buildbucket for the build status
[03:41:26.865] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:26.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:42:0) after 1m0s
[03:42:26.922] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:42:0)
[03:42:26.922] Timer tick, asking Buildbucket for the build status
[03:42:27.179] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:27.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:43:0) after 1m0s
[03:43:27.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:43:0)
[03:43:27.204] Timer tick, asking Buildbucket for the build status
[03:43:27.291] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:27.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:44:0) after 1m0s
[03:44:27.382] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:44:0)
[03:44:27.382] Timer tick, asking Buildbucket for the build status
[03:44:27.447] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:27.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:45:0) after 1m0s
[03:45:27.474] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:45:0)
[03:45:27.474] Timer tick, asking Buildbucket for the build status
[03:45:27.626] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:27.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:46:0) after 1m0s
[03:46:27.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:46:0)
[03:46:27.666] Timer tick, asking Buildbucket for the build status
[03:46:27.897] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:27.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:47:0) after 1m0s
[03:47:27.923] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:47:0)
[03:47:27.923] Timer tick, asking Buildbucket for the build status
[03:47:28.041] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:28.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:48:0) after 1m0s
[03:48:28.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:48:0)
[03:48:28.069] Timer tick, asking Buildbucket for the build status
[03:48:28.231] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:28.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:49:0) after 1m0s
[03:49:28.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:49:0)
[03:49:28.314] Timer tick, asking Buildbucket for the build status
[03:49:28.384] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:28.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:50:0) after 1m0s
[03:50:28.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:50:0)
[03:50:28.464] Timer tick, asking Buildbucket for the build status
[03:50:28.538] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:28.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:51:0) after 1m0s
[03:51:28.617] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:51:0)
[03:51:28.617] Timer tick, asking Buildbucket for the build status
[03:51:28.871] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:28.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:52:0) after 1m0s
[03:52:28.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:52:0)
[03:52:28.887] Timer tick, asking Buildbucket for the build status
[03:52:28.983] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:28.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:53:0) after 1m0s
[03:53:29.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:53:0)
[03:53:29.008] Timer tick, asking Buildbucket for the build status
[03:53:29.093] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:29.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:54:0) after 1m0s
[03:54:29.120] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:54:0)
[03:54:29.120] Timer tick, asking Buildbucket for the build status
[03:54:29.196] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:29.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:55:0) after 1m0s
[03:55:29.276] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:55:0)
[03:55:29.276] Timer tick, asking Buildbucket for the build status
[03:55:29.364] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:29.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:56:0) after 1m0s
[03:56:29.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:56:0)
[03:56:29.395] Timer tick, asking Buildbucket for the build status
[03:56:29.498] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:29.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:57:0) after 1m0s
[03:57:29.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:57:0)
[03:57:29.527] Timer tick, asking Buildbucket for the build status
[03:57:29.672] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:29.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:58:0) after 1m0s
[03:58:29.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:58:0)
[03:58:29.704] Timer tick, asking Buildbucket for the build status
[03:58:29.756] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:29.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:59:0) after 1m0s
[03:59:29.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:59:0)
[03:59:29.795] Timer tick, asking Buildbucket for the build status
[03:59:29.860] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:29.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:60:0) after 1m0s
[04:00:29.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:60:0)
[04:00:29.892] Timer tick, asking Buildbucket for the build status
[04:00:29.978] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:29.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:61:0) after 1m0s
[04:01:30.007] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:61:0)
[04:01:30.007] Timer tick, asking Buildbucket for the build status
[04:01:30.064] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:30.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:62:0) after 1m0s
[04:02:30.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:62:0)
[04:02:30.093] Timer tick, asking Buildbucket for the build status
[04:02:30.579] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:30.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:63:0) after 1m0s
[04:03:30.609] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:63:0)
[04:03:30.609] Timer tick, asking Buildbucket for the build status
[04:03:30.914] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:30.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:64:0) after 1m0s
[04:04:30.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:64:0)
[04:04:30.938] Timer tick, asking Buildbucket for the build status
[04:04:31.016] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:31.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:65:0) after 1m0s
[04:05:31.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:65:0)
[04:05:31.035] Timer tick, asking Buildbucket for the build status
[04:05:31.441] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:31.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:66:0) after 1m0s
[04:06:31.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:66:0)
[04:06:31.467] Timer tick, asking Buildbucket for the build status
[04:06:31.531] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:31.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:67:0) after 1m0s
[04:07:31.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:67:0)
[04:07:31.575] Timer tick, asking Buildbucket for the build status
[04:07:31.903] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:31.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:68:0) after 1m0s
[04:08:31.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:68:0)
[04:08:31.927] Timer tick, asking Buildbucket for the build status
[04:08:32.040] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:32.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:69:0) after 1m0s
[04:09:32.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:69:0)
[04:09:32.072] Timer tick, asking Buildbucket for the build status
[04:09:32.131] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:32.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:70:0) after 1m0s
[04:10:32.198] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:70:0)
[04:10:32.198] Timer tick, asking Buildbucket for the build status
[04:10:32.254] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:32.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:71:0) after 1m0s
[04:11:32.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:71:0)
[04:11:32.295] Timer tick, asking Buildbucket for the build status
[04:11:32.398] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:32.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:72:0) after 1m0s
[04:12:32.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:72:0)
[04:12:32.416] Timer tick, asking Buildbucket for the build status
[04:12:32.549] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:32.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:73:0) after 1m0s
[04:13:32.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:73:0)
[04:13:32.597] Timer tick, asking Buildbucket for the build status
[04:13:32.653] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:32.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:74:0) after 1m0s
[04:14:32.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:74:0)
[04:14:32.750] Timer tick, asking Buildbucket for the build status
[04:14:32.814] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:32.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:75:0) after 1m0s
[04:15:32.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:75:0)
[04:15:32.957] Timer tick, asking Buildbucket for the build status
[04:15:33.027] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:33.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:76:0) after 1m0s
[04:16:33.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:76:0)
[04:16:33.023] Timer tick, asking Buildbucket for the build status
[04:16:33.190] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:33.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:77:0) after 1m0s
[04:17:33.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:77:0)
[04:17:33.258] Timer tick, asking Buildbucket for the build status
[04:17:33.355] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:33.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:78:0) after 1m0s
[04:18:33.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:78:0)
[04:18:33.397] Timer tick, asking Buildbucket for the build status
[04:18:33.468] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:33.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:79:0) after 1m0s
[04:19:33.523] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:79:0)
[04:19:33.523] Timer tick, asking Buildbucket for the build status
[04:19:33.577] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:33.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:80:0) after 1m0s
[04:20:33.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:80:0)
[04:20:33.662] Timer tick, asking Buildbucket for the build status
[04:20:33.748] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:33.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:81:0) after 1m0s
[04:21:33.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:81:0)
[04:21:33.873] Timer tick, asking Buildbucket for the build status
[04:21:33.938] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:33.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:82:0) after 1m0s
[04:22:33.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:82:0)
[04:22:33.968] Timer tick, asking Buildbucket for the build status
[04:22:34.080] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:34.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:83:0) after 1m0s
[04:23:34.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:83:0)
[04:23:34.168] Timer tick, asking Buildbucket for the build status
[04:23:34.298] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:34.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:84:0) after 1m0s
[04:24:34.336] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:84:0)
[04:24:34.336] Timer tick, asking Buildbucket for the build status
[04:24:34.862] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:34.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:85:0) after 1m0s
[04:25:35.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:85:0)
[04:25:35.178] Timer tick, asking Buildbucket for the build status
[04:25:35.238] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:35.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:86:0) after 1m0s
[04:26:35.333] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:86:0)
[04:26:35.333] Timer tick, asking Buildbucket for the build status
[04:26:35.404] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:35.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:87:0) after 1m0s
[04:27:35.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:87:0)
[04:27:35.519] Timer tick, asking Buildbucket for the build status
[04:27:35.657] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:35.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:88:0) after 1m0s
[04:28:35.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:88:0)
[04:28:35.761] Timer tick, asking Buildbucket for the build status
[04:28:35.946] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:35.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:89:0) after 1m0s
[04:29:36.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:89:0)
[04:29:36.083] Timer tick, asking Buildbucket for the build status
[04:29:36.146] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:36.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:90:0) after 1m0s
[04:30:36.190] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:90:0)
[04:30:36.190] Timer tick, asking Buildbucket for the build status
[04:30:36.321] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:36.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:91:0) after 1m0s
[04:31:36.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:91:0)
[04:31:36.349] Timer tick, asking Buildbucket for the build status
[04:31:36.507] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:36.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:92:0) after 1m0s
[04:32:36.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:92:0)
[04:32:36.608] Timer tick, asking Buildbucket for the build status
[04:32:36.727] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:36.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:93:0) after 1m0s
[04:33:36.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:93:0)
[04:33:36.795] Timer tick, asking Buildbucket for the build status
[04:33:39.579] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:39.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:94:0) after 1m0s
[04:34:39.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:94:0)
[04:34:39.616] Timer tick, asking Buildbucket for the build status
[04:34:39.693] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:39.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:95:0) after 1m0s
[04:35:39.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:95:0)
[04:35:39.719] Timer tick, asking Buildbucket for the build status
[04:35:39.774] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:39.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:96:0) after 1m0s
[04:36:39.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:96:0)
[04:36:39.803] Timer tick, asking Buildbucket for the build status
[04:36:39.861] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:39.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:97:0) after 1m0s
[04:37:39.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:97:0)
[04:37:39.891] Timer tick, asking Buildbucket for the build status
[04:37:40.192] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:40.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:98:0) after 1m0s
[04:38:40.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:98:0)
[04:38:40.239] Timer tick, asking Buildbucket for the build status
[04:38:40.606] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:40.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:99:0) after 1m0s
[04:39:40.635] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:99:0)
[04:39:40.635] Timer tick, asking Buildbucket for the build status
[04:39:40.820] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:40.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:100:0) after 1m0s
[04:40:40.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:100:0)
[04:40:40.872] Timer tick, asking Buildbucket for the build status
[04:40:40.943] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:40.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:101:0) after 1m0s
[04:41:41.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:101:0)
[04:41:41.966] Timer tick, asking Buildbucket for the build status
[04:41:42.107] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:42.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:102:0) after 1m0s
[04:42:42.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:102:0)
[04:42:42.185] Timer tick, asking Buildbucket for the build status
[04:42:42.294] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:42.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:103:0) after 1m0s
[04:43:42.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:103:0)
[04:43:42.307] Timer tick, asking Buildbucket for the build status
[04:43:42.371] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:42.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:104:0) after 1m0s
[04:44:42.487] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:104:0)
[04:44:42.487] Timer tick, asking Buildbucket for the build status
[04:44:42.545] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:42.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:105:0) after 1m0s
[04:45:42.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:105:0)
[04:45:42.614] Timer tick, asking Buildbucket for the build status
[04:45:42.673] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:42.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:106:0) after 1m0s
[04:46:42.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:106:0)
[04:46:42.673] Timer tick, asking Buildbucket for the build status
[04:46:42.896] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:42.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:107:0) after 1m0s
[04:47:42.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:107:0)
[04:47:42.972] Timer tick, asking Buildbucket for the build status
[04:47:43.093] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:43.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:108:0) after 1m0s
[04:48:43.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:108:0)
[04:48:43.141] Timer tick, asking Buildbucket for the build status
[04:48:43.209] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:43.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:109:0) after 1m0s
[04:49:43.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:109:0)
[04:49:43.269] Timer tick, asking Buildbucket for the build status
[04:49:43.495] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:43.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:110:0) after 1m0s
[04:50:43.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:110:0)
[04:50:43.517] Timer tick, asking Buildbucket for the build status
[04:50:43.580] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:43.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:111:0) after 1m0s
[04:51:43.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:111:0)
[04:51:43.682] Timer tick, asking Buildbucket for the build status
[04:51:44.163] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:44.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:112:0) after 1m0s
[04:52:44.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:112:0)
[04:52:44.172] Timer tick, asking Buildbucket for the build status
[04:52:44.325] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:44.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:113:0) after 1m0s
[04:53:44.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:113:0)
[04:53:44.357] Timer tick, asking Buildbucket for the build status
[04:53:44.430] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:44.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:114:0) after 1m0s
[04:54:44.523] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:114:0)
[04:54:44.523] Timer tick, asking Buildbucket for the build status
[04:54:44.594] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:44.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:115:0) after 1m0s
[04:55:44.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:115:0)
[04:55:44.677] Timer tick, asking Buildbucket for the build status
[04:55:44.815] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:44.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:116:0) after 1m0s
[04:56:44.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:116:0)
[04:56:44.881] Timer tick, asking Buildbucket for the build status
[04:56:44.964] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:44.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:117:0) after 1m0s
[04:57:45.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:117:0)
[04:57:45.062] Timer tick, asking Buildbucket for the build status
[04:57:45.496] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:45.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:118:0) after 1m0s
[04:58:45.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:118:0)
[04:58:45.527] Timer tick, asking Buildbucket for the build status
[04:58:45.586] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:45.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:119:0) after 1m0s
[04:59:45.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:119:0)
[04:59:45.616] Timer tick, asking Buildbucket for the build status
[04:59:45.674] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:45.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:120:0) after 1m0s
[05:00:47.408] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:120:0)
[05:00:47.408] Timer tick, asking Buildbucket for the build status
[05:00:47.517] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:47.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:121:0) after 1m0s
[05:01:47.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:121:0)
[05:01:47.657] Timer tick, asking Buildbucket for the build status
[05:01:47.835] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:47.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:122:0) after 1m0s
[05:02:47.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:122:0)
[05:02:47.971] Timer tick, asking Buildbucket for the build status
[05:02:50.020] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:50.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:123:0) after 1m0s
[05:03:50.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:123:0)
[05:03:50.532] Timer tick, asking Buildbucket for the build status
[05:03:50.602] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:50.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:124:0) after 1m0s
[05:04:50.791] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:124:0)
[05:04:50.791] Timer tick, asking Buildbucket for the build status
[05:04:50.987] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:50.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:125:0) after 1m0s
[05:05:51.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:125:0)
[05:05:51.067] Timer tick, asking Buildbucket for the build status
[05:05:51.188] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:51.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:126:0) after 1m0s
[05:06:51.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:126:0)
[05:06:51.218] Timer tick, asking Buildbucket for the build status
[05:06:51.513] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:51.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:127:0) after 1m0s
[05:07:51.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:127:0)
[05:07:51.652] Timer tick, asking Buildbucket for the build status
[05:07:51.818] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:51.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:128:0) after 1m0s
[05:08:51.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:128:0)
[05:08:51.836] Timer tick, asking Buildbucket for the build status
[05:08:51.904] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:51.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:129:0) after 1m0s
[05:09:52.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:129:0)
[05:09:52.104] Timer tick, asking Buildbucket for the build status
[05:09:52.379] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:52.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:130:0) after 1m0s
[05:10:52.445] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:130:0)
[05:10:52.445] Timer tick, asking Buildbucket for the build status
[05:10:52.503] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:52.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:131:0) after 1m0s
[05:11:52.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:131:0)
[05:11:52.531] Timer tick, asking Buildbucket for the build status
[05:11:52.580] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:52.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:132:0) after 1m0s
[05:12:52.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:132:0)
[05:12:52.630] Timer tick, asking Buildbucket for the build status
[05:12:52.810] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:52.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:133:0) after 1m0s
[05:13:52.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:133:0)
[05:13:52.808] Timer tick, asking Buildbucket for the build status
[05:13:52.897] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:52.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:134:0) after 1m0s
[05:14:52.975] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:134:0)
[05:14:52.975] Timer tick, asking Buildbucket for the build status
[05:14:53.043] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:53.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:135:0) after 1m0s
[05:15:53.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:135:0)
[05:15:53.414] Timer tick, asking Buildbucket for the build status
[05:15:53.627] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:53.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:136:0) after 1m0s
[05:16:53.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:136:0)
[05:16:53.746] Timer tick, asking Buildbucket for the build status
[05:16:53.817] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:53.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:137:0) after 1m0s
[05:17:53.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:137:0)
[05:17:53.924] Timer tick, asking Buildbucket for the build status
[05:17:54.035] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:54.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:138:0) after 1m0s
[05:18:54.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:138:0)
[05:18:54.088] Timer tick, asking Buildbucket for the build status
[05:18:54.154] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:54.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:139:0) after 1m0s
[05:19:54.301] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:139:0)
[05:19:54.301] Timer tick, asking Buildbucket for the build status
[05:19:54.364] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:54.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:140:0) after 1m0s
[05:20:54.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:140:0)
[05:20:54.429] Timer tick, asking Buildbucket for the build status
[05:20:54.999] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:54.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:141:0) after 1m0s
[05:21:55.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:141:0)
[05:21:55.131] Timer tick, asking Buildbucket for the build status
[05:21:55.193] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:55.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:142:0) after 1m0s
[05:22:55.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:142:0)
[05:22:55.268] Timer tick, asking Buildbucket for the build status
[05:22:55.393] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:55.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:143:0) after 1m0s
[05:23:55.494] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:143:0)
[05:23:55.494] Timer tick, asking Buildbucket for the build status
[05:23:55.559] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:55.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:144:0) after 1m0s
[05:24:55.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:144:0)
[05:24:55.637] Timer tick, asking Buildbucket for the build status
[05:24:55.695] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:55.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:145:0) after 1m0s
[05:25:55.725] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:145:0)
[05:25:55.725] Timer tick, asking Buildbucket for the build status
[05:25:55.778] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:55.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:146:0) after 1m0s
[05:26:55.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:146:0)
[05:26:55.808] Timer tick, asking Buildbucket for the build status
[05:26:56.501] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:56.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:147:0) after 1m0s
[05:27:56.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:147:0)
[05:27:56.501] Timer tick, asking Buildbucket for the build status
[05:27:56.678] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:56.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:148:0) after 1m0s
[05:28:56.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:148:0)
[05:28:56.757] Timer tick, asking Buildbucket for the build status
[05:28:56.815] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:56.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:149:0) after 1m0s
[05:29:56.866] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:149:0)
[05:29:56.866] Timer tick, asking Buildbucket for the build status
[05:29:56.946] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:56.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:150:0) after 1m0s
[05:30:57.060] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:150:0)
[05:30:57.060] Timer tick, asking Buildbucket for the build status
[05:30:57.204] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:57.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:151:0) after 1m0s
[05:31:57.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:151:0)
[05:31:57.282] Timer tick, asking Buildbucket for the build status
[05:31:57.351] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:57.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:152:0) after 1m0s
[05:32:57.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:152:0)
[05:32:57.452] Timer tick, asking Buildbucket for the build status
[05:32:57.771] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:57.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:153:0) after 1m0s
[05:33:57.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:153:0)
[05:33:57.803] Timer tick, asking Buildbucket for the build status
[05:33:57.869] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:57.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:154:0) after 1m0s
[05:34:57.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:154:0)
[05:34:57.949] Timer tick, asking Buildbucket for the build status
[05:34:58.013] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:58.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:155:0) after 1m0s
[05:35:58.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:155:0)
[05:35:58.009] Timer tick, asking Buildbucket for the build status
[05:35:58.100] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:58.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:156:0) after 1m0s
[05:36:58.139] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:156:0)
[05:36:58.139] Timer tick, asking Buildbucket for the build status
[05:36:58.206] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:58.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:157:0) after 1m0s
[05:37:58.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:157:0)
[05:37:58.239] Timer tick, asking Buildbucket for the build status
[05:37:58.341] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:58.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:158:0) after 1m0s
[05:38:58.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:158:0)
[05:38:58.368] Timer tick, asking Buildbucket for the build status
[05:38:59.610] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:59.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:159:0) after 1m0s
[05:39:59.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:159:0)
[05:39:59.646] Timer tick, asking Buildbucket for the build status
[05:39:59.713] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:59.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:160:0) after 1m0s
[05:40:59.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:160:0)
[05:40:59.745] Timer tick, asking Buildbucket for the build status
[05:40:59.809] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:59.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:161:0) after 1m0s
[05:41:59.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:161:0)
[05:41:59.794] Timer tick, asking Buildbucket for the build status
[05:41:59.860] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:59.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:162:0) after 1m0s
[05:42:59.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:162:0)
[05:42:59.888] Timer tick, asking Buildbucket for the build status
[05:43:00.575] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:00.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:163:0) after 1m0s
[05:44:00.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:163:0)
[05:44:00.600] Timer tick, asking Buildbucket for the build status
[05:44:00.670] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:00.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:164:0) after 1m0s
[05:45:00.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:164:0)
[05:45:00.707] Timer tick, asking Buildbucket for the build status
[05:45:01.009] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:01.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:165:0) after 1m0s
[05:46:01.954] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:165:0)
[05:46:01.954] Timer tick, asking Buildbucket for the build status
[05:46:02.034] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:02.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:166:0) after 1m0s
[05:47:02.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:166:0)
[05:47:02.738] Timer tick, asking Buildbucket for the build status
[05:47:02.795] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:02.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:167:0) after 1m0s
[05:48:02.806] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:167:0)
[05:48:02.806] Timer tick, asking Buildbucket for the build status
[05:48:02.988] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:02.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:168:0) after 1m0s
[05:49:03.028] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:168:0)
[05:49:03.028] Timer tick, asking Buildbucket for the build status
[05:49:03.120] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:03.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:169:0) after 1m0s
[05:50:03.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:169:0)
[05:50:03.152] Timer tick, asking Buildbucket for the build status
[05:50:03.219] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:03.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:170:0) after 1m0s
[05:51:03.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:170:0)
[05:51:03.247] Timer tick, asking Buildbucket for the build status
[05:51:03.315] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:03.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:171:0) after 1m0s
[05:52:03.351] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:171:0)
[05:52:03.351] Timer tick, asking Buildbucket for the build status
[05:52:03.534] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:03.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:172:0) after 1m0s
[05:53:03.558] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:172:0)
[05:53:03.558] Timer tick, asking Buildbucket for the build status
[05:53:03.711] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:03.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:173:0) after 1m0s
[05:54:03.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:173:0)
[05:54:03.704] Timer tick, asking Buildbucket for the build status
[05:54:03.765] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:03.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:174:0) after 1m0s
[05:55:03.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:174:0)
[05:55:03.847] Timer tick, asking Buildbucket for the build status
[05:55:04.065] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:04.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:175:0) after 1m0s
[05:56:04.180] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:175:0)
[05:56:04.180] Timer tick, asking Buildbucket for the build status
[05:56:04.310] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:04.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:176:0) after 1m0s
[05:57:04.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:176:0)
[05:57:04.404] Timer tick, asking Buildbucket for the build status
[05:57:04.717] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:04.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:177:0) after 1m0s
[05:58:04.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:177:0)
[05:58:04.863] Timer tick, asking Buildbucket for the build status
[05:58:05.009] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:05.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:178:0) after 1m0s
[05:59:05.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:178:0)
[05:59:05.106] Timer tick, asking Buildbucket for the build status
[05:59:05.201] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:05.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:179:0) after 1m0s
[06:00:05.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:179:0)
[06:00:05.392] Timer tick, asking Buildbucket for the build status
[06:00:05.460] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:05.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:180:0) after 1m0s
[06:01:05.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:180:0)
[06:01:05.920] Timer tick, asking Buildbucket for the build status
[06:01:06.034] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:06.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:181:0) after 1m0s
[06:02:06.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:181:0)
[06:02:06.086] Timer tick, asking Buildbucket for the build status
[06:02:06.146] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:06.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:182:0) after 1m0s
[06:03:06.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:182:0)
[06:03:06.351] Timer tick, asking Buildbucket for the build status
[06:03:06.453] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:06.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:183:0) after 1m0s
[06:04:06.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:183:0)
[06:04:06.525] Timer tick, asking Buildbucket for the build status
[06:04:06.596] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:06.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:184:0) after 1m0s
[06:05:06.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:184:0)
[06:05:06.626] Timer tick, asking Buildbucket for the build status
[06:05:06.681] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:06.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:185:0) after 1m0s
[06:06:06.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:185:0)
[06:06:06.715] Timer tick, asking Buildbucket for the build status
[06:06:06.791] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:06.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:186:0) after 1m0s
[06:07:06.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:186:0)
[06:07:06.821] Timer tick, asking Buildbucket for the build status
[06:07:07.082] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:07.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:187:0) after 1m0s
[06:08:07.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:187:0)
[06:08:07.286] Timer tick, asking Buildbucket for the build status
[06:08:07.422] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:07.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:188:0) after 1m0s
[06:09:07.508] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:188:0)
[06:09:07.508] Timer tick, asking Buildbucket for the build status
[06:09:07.636] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:07.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:189:0) after 1m0s
[06:10:07.769] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:189:0)
[06:10:07.769] Timer tick, asking Buildbucket for the build status
[06:10:07.938] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:07.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:190:0) after 1m0s
[06:11:08.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:190:0)
[06:11:08.033] Timer tick, asking Buildbucket for the build status
[06:11:08.135] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:08.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:191:0) after 1m0s
[06:12:08.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:191:0)
[06:12:08.215] Timer tick, asking Buildbucket for the build status
[06:12:08.279] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:08.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:192:0) after 1m0s
[06:13:08.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:192:0)
[06:13:08.481] Timer tick, asking Buildbucket for the build status
[06:13:08.628] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:08.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:193:0) after 1m0s
[06:14:08.801] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:193:0)
[06:14:08.801] Timer tick, asking Buildbucket for the build status
[06:14:08.873] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:08.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:194:0) after 1m0s
[06:15:08.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:194:0)
[06:15:08.997] Timer tick, asking Buildbucket for the build status
[06:15:09.065] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:09.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:195:0) after 1m0s
[06:16:09.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:195:0)
[06:16:09.105] Timer tick, asking Buildbucket for the build status
[06:16:09.176] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:09.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:196:0) after 1m0s
[06:17:09.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:196:0)
[06:17:09.268] Timer tick, asking Buildbucket for the build status
[06:17:09.438] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:09.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:197:0) after 1m0s
[06:18:09.473] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:197:0)
[06:18:09.473] Timer tick, asking Buildbucket for the build status
[06:18:09.901] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:09.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:198:0) after 1m0s
[06:19:09.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:198:0)
[06:19:09.965] Timer tick, asking Buildbucket for the build status
[06:19:10.576] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:10.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:199:0) after 1m0s
[06:20:10.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:199:0)
[06:20:10.672] Timer tick, asking Buildbucket for the build status
[06:20:10.747] Build 8866861509663967520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:10.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032291572329909232:200:0) after 1m0s
[06:20:31.755] Received PubSub notification, asking Buildbucket for the build status
[06:20:31.836] Build 8866861509663967520: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:20:31.836] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1602310830534489",
"created_by": "project:chromium",
"created_ts": "1602298933310679",
"id": "8866861509663967520",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"09d89563a118e1bb0987f27d2fd0738774f1b59e\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"09d89563a118e1bb0987f27d2fd0738774f1b59e\", \"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-379q\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 780, \"got_angle_revision\": \"b2ff69f856d98312bbfe14f80d084b7d8795281f\", \"got_dawn_revision\": \"1c25198384e7ecfebbd3ac84203d3b1d46a0e228\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"09d89563a118e1bb0987f27d2fd0738774f1b59e\", \"got_revision_cp\": \"refs/heads/master@{#815908}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"0aca3ca9427d1d2aa8d8e706de62360c61af3598\", \"got_v8_revision\": \"07b0b1dcde4a99294b8028d83f4ea244885cc091\", \"got_v8_revision_cp\": \"refs/heads/8.8.62@{#1}\", \"got_webrtc_revision\": \"55169d89c60e246b078099f584f6d45f040f8942\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32374}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"09d89563a118e1bb0987f27d2fd0738774f1b59e\"}, \"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-379q\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5570-fa4adf0\"], \"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": "1602298970250896",
"status": "COMPLETED",
"status_changed_ts": "1602310831280429",
"tags": [
"build_address:luci.chromium.ci/win-official/780",
"builder:win-official",
"buildset:commit/git/09d89563a118e1bb0987f27d2fd0738774f1b59e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09d89563a118e1bb0987f27d2fd0738774f1b59e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032291572329909232",
"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/8866861509663967520/+/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:4f294700d19d9610",
"user_agent:luci-scheduler"
],
"updated_ts": "1602310831372060",
"url": "https://ci.chromium.org/b/8866861509663967520",
"utcnow_ts": "1602310831817863"
}
}
[06:20:31.836] Invocation finished in 3h18m20.452604643s with status SUCCEEDED