[17:16:00.366] New invocation is queued and will start shortly
[17:16:02.101] Starting the invocation (attempt 1)
[17:16:02.146] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:16:02.146] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:16:02.146] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037945463750873952",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dab549a6c9a8f85ab2ea527e97479767e2f6c240\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9037945463750873952",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/dab549a6c9a8f85ab2ea527e97479767e2f6c240",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dab549a6c9a8f85ab2ea527e97479767e2f6c240",
"gitiles_ref:refs/heads/master"
]
}
[17:16:02.939] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1596906962272880",
"id": "8872515401087197328",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dab549a6c9a8f85ab2ea527e97479767e2f6c240\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596906962804977",
"tags": [
"build_address:luci.chromium.ci/win-official/289",
"builder:win-official",
"buildset:commit/git/dab549a6c9a8f85ab2ea527e97479767e2f6c240",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dab549a6c9a8f85ab2ea527e97479767e2f6c240",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037945463750873952",
"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/8872515401087197328/+/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": "1596906962805094",
"url": "https://ci.chromium.org/b/8872515401087197328",
"utcnow_ts": "1596906962931032"
}
}
[17:16:02.939] Task URL: https://ci.chromium.org/b/8872515401087197328
[17:16:02.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:2:0) after 1m0s
[17:16:53.180] Received PubSub notification, asking Buildbucket for the build status
[17:16:53.310] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:06.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:2:0)
[17:17:06.384] Timer tick, asking Buildbucket for the build status
[17:17:06.494] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:06.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:4:0) after 1m0s
[17:18:06.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:4:0)
[17:18:06.554] Timer tick, asking Buildbucket for the build status
[17:18:06.719] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:06.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:5:0) after 1m0s
[17:19:06.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:5:0)
[17:19:06.850] Timer tick, asking Buildbucket for the build status
[17:19:07.297] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:07.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:6:0) after 1m0s
[17:20:07.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:6:0)
[17:20:07.384] Timer tick, asking Buildbucket for the build status
[17:20:08.038] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:08.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:7:0) after 1m0s
[17:21:08.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:7:0)
[17:21:08.067] Timer tick, asking Buildbucket for the build status
[17:21:08.195] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:08.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:8:0) after 1m0s
[17:22:08.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:8:0)
[17:22:08.238] Timer tick, asking Buildbucket for the build status
[17:22:08.520] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:08.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:9:0) after 1m0s
[17:23:08.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:9:0)
[17:23:08.601] Timer tick, asking Buildbucket for the build status
[17:23:08.707] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:08.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:10:0) after 1m0s
[17:24:08.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:10:0)
[17:24:08.808] Timer tick, asking Buildbucket for the build status
[17:24:08.883] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:08.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:11:0) after 1m0s
[17:25:08.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:11:0)
[17:25:08.962] Timer tick, asking Buildbucket for the build status
[17:25:09.344] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:09.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:12:0) after 1m0s
[17:26:09.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:12:0)
[17:26:09.368] Timer tick, asking Buildbucket for the build status
[17:26:09.479] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:09.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:13:0) after 1m0s
[17:27:09.508] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:13:0)
[17:27:09.508] Timer tick, asking Buildbucket for the build status
[17:27:09.737] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:09.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:14:0) after 1m0s
[17:28:09.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:14:0)
[17:28:09.763] Timer tick, asking Buildbucket for the build status
[17:28:09.889] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:09.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:15:0) after 1m0s
[17:29:09.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:15:0)
[17:29:09.962] Timer tick, asking Buildbucket for the build status
[17:29:10.544] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:10.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:16:0) after 1m0s
[17:30:10.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:16:0)
[17:30:10.569] Timer tick, asking Buildbucket for the build status
[17:30:10.637] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:10.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:17:0) after 1m0s
[17:31:10.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:17:0)
[17:31:10.700] Timer tick, asking Buildbucket for the build status
[17:31:10.823] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:10.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:18:0) after 1m0s
[17:32:10.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:18:0)
[17:32:10.864] Timer tick, asking Buildbucket for the build status
[17:32:10.934] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:10.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:19:0) after 1m0s
[17:33:10.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:19:0)
[17:33:10.974] Timer tick, asking Buildbucket for the build status
[17:33:11.126] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:11.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:20:0) after 1m0s
[17:34:11.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:20:0)
[17:34:11.187] Timer tick, asking Buildbucket for the build status
[17:34:12.668] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:12.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:21:0) after 1m0s
[17:35:12.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:21:0)
[17:35:12.688] Timer tick, asking Buildbucket for the build status
[17:35:12.821] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:12.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:22:0) after 1m0s
[17:36:12.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:22:0)
[17:36:12.854] Timer tick, asking Buildbucket for the build status
[17:36:12.996] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:12.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:23:0) after 1m0s
[17:37:13.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:23:0)
[17:37:13.076] Timer tick, asking Buildbucket for the build status
[17:37:13.215] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:13.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:24:0) after 1m0s
[17:38:13.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:24:0)
[17:38:13.261] Timer tick, asking Buildbucket for the build status
[17:38:13.956] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:13.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:25:0) after 1m0s
[17:39:13.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:25:0)
[17:39:13.982] Timer tick, asking Buildbucket for the build status
[17:39:14.057] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:14.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:26:0) after 1m0s
[17:40:14.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:26:0)
[17:40:14.094] Timer tick, asking Buildbucket for the build status
[17:40:14.209] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:14.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:27:0) after 1m0s
[17:41:14.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:27:0)
[17:41:14.235] Timer tick, asking Buildbucket for the build status
[17:41:14.705] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:14.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:28:0) after 1m0s
[17:42:14.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:28:0)
[17:42:14.730] Timer tick, asking Buildbucket for the build status
[17:42:14.828] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:14.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:29:0) after 1m0s
[17:43:14.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:29:0)
[17:43:14.867] Timer tick, asking Buildbucket for the build status
[17:43:14.930] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:14.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:30:0) after 1m0s
[17:44:15.011] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:30:0)
[17:44:15.011] Timer tick, asking Buildbucket for the build status
[17:44:15.106] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:15.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:31:0) after 1m0s
[17:45:15.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:31:0)
[17:45:15.169] Timer tick, asking Buildbucket for the build status
[17:45:15.265] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:15.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:32:0) after 1m0s
[17:46:15.308] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:32:0)
[17:46:15.308] Timer tick, asking Buildbucket for the build status
[17:46:15.484] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:15.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:33:0) after 1m0s
[17:47:15.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:33:0)
[17:47:15.528] Timer tick, asking Buildbucket for the build status
[17:47:15.812] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:15.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:34:0) after 1m0s
[17:48:15.838] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:34:0)
[17:48:15.838] Timer tick, asking Buildbucket for the build status
[17:48:15.943] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:15.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:35:0) after 1m0s
[17:49:15.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:35:0)
[17:49:15.969] Timer tick, asking Buildbucket for the build status
[17:49:16.098] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:16.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:36:0) after 1m0s
[17:50:16.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:36:0)
[17:50:16.127] Timer tick, asking Buildbucket for the build status
[17:50:16.228] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:16.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:37:0) after 1m0s
[17:51:16.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:37:0)
[17:51:16.302] Timer tick, asking Buildbucket for the build status
[17:51:16.616] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:16.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:38:0) after 1m0s
[17:52:16.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:38:0)
[17:52:16.650] Timer tick, asking Buildbucket for the build status
[17:52:16.990] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:16.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:39:0) after 1m0s
[17:53:17.046] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:39:0)
[17:53:17.046] Timer tick, asking Buildbucket for the build status
[17:53:17.147] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:17.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:40:0) after 1m0s
[17:54:17.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:40:0)
[17:54:17.217] Timer tick, asking Buildbucket for the build status
[17:54:17.279] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:17.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:41:0) after 1m0s
[17:55:17.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:41:0)
[17:55:17.366] Timer tick, asking Buildbucket for the build status
[17:55:17.445] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:17.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:42:0) after 1m0s
[17:56:17.468] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:42:0)
[17:56:17.468] Timer tick, asking Buildbucket for the build status
[17:56:17.807] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:17.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:43:0) after 1m0s
[17:57:17.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:43:0)
[17:57:17.930] Timer tick, asking Buildbucket for the build status
[17:57:17.998] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:17.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:44:0) after 1m0s
[17:58:18.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:44:0)
[17:58:18.029] Timer tick, asking Buildbucket for the build status
[17:58:18.190] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:18.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:45:0) after 1m0s
[17:59:18.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:45:0)
[17:59:18.219] Timer tick, asking Buildbucket for the build status
[17:59:18.317] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:18.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:46:0) after 1m0s
[18:00:18.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:46:0)
[18:00:18.306] Timer tick, asking Buildbucket for the build status
[18:00:18.387] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:18.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:47:0) after 1m0s
[18:01:18.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:47:0)
[18:01:18.419] Timer tick, asking Buildbucket for the build status
[18:01:18.574] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:18.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:48:0) after 1m0s
[18:02:18.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:48:0)
[18:02:18.708] Timer tick, asking Buildbucket for the build status
[18:02:18.889] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:18.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:49:0) after 1m0s
[18:03:18.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:49:0)
[18:03:18.955] Timer tick, asking Buildbucket for the build status
[18:03:19.082] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:19.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:50:0) after 1m0s
[18:04:19.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:50:0)
[18:04:19.449] Timer tick, asking Buildbucket for the build status
[18:04:19.548] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:19.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:51:0) after 1m0s
[18:05:19.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:51:0)
[18:05:19.530] Timer tick, asking Buildbucket for the build status
[18:05:19.764] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:19.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:52:0) after 1m0s
[18:06:19.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:52:0)
[18:06:19.754] Timer tick, asking Buildbucket for the build status
[18:06:20.165] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:20.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:53:0) after 1m0s
[18:07:20.202] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:53:0)
[18:07:20.202] Timer tick, asking Buildbucket for the build status
[18:07:20.271] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:20.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:54:0) after 1m0s
[18:08:20.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:54:0)
[18:08:20.312] Timer tick, asking Buildbucket for the build status
[18:08:20.444] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:20.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:55:0) after 1m0s
[18:09:20.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:55:0)
[18:09:20.482] Timer tick, asking Buildbucket for the build status
[18:09:20.675] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:20.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:56:0) after 1m0s
[18:10:20.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:56:0)
[18:10:20.719] Timer tick, asking Buildbucket for the build status
[18:10:22.729] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:22.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:57:0) after 1m0s
[18:11:22.860] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:57:0)
[18:11:22.860] Timer tick, asking Buildbucket for the build status
[18:11:23.074] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:23.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:58:0) after 1m0s
[18:12:23.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:58:0)
[18:12:23.076] Timer tick, asking Buildbucket for the build status
[18:12:23.483] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:23.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:59:0) after 1m0s
[18:13:23.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:59:0)
[18:13:23.548] Timer tick, asking Buildbucket for the build status
[18:13:23.647] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:23.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:60:0) after 1m0s
[18:14:23.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:60:0)
[18:14:23.669] Timer tick, asking Buildbucket for the build status
[18:14:23.736] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:23.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:61:0) after 1m0s
[18:15:23.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:61:0)
[18:15:23.764] Timer tick, asking Buildbucket for the build status
[18:15:23.837] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:23.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:62:0) after 1m0s
[18:16:23.954] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:62:0)
[18:16:23.954] Timer tick, asking Buildbucket for the build status
[18:16:24.388] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:24.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:63:0) after 1m0s
[18:17:24.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:63:0)
[18:17:24.415] Timer tick, asking Buildbucket for the build status
[18:17:24.612] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:24.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:64:0) after 1m0s
[18:18:24.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:64:0)
[18:18:24.653] Timer tick, asking Buildbucket for the build status
[18:18:24.722] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:24.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:65:0) after 1m0s
[18:19:24.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:65:0)
[18:19:24.761] Timer tick, asking Buildbucket for the build status
[18:19:24.846] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:24.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:66:0) after 1m0s
[18:20:24.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:66:0)
[18:20:24.903] Timer tick, asking Buildbucket for the build status
[18:20:24.968] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:24.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:67:0) after 1m0s
[18:21:25.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:67:0)
[18:21:25.079] Timer tick, asking Buildbucket for the build status
[18:21:25.283] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:25.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:68:0) after 1m0s
[18:22:25.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:68:0)
[18:22:25.310] Timer tick, asking Buildbucket for the build status
[18:22:25.400] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:25.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:69:0) after 1m0s
[18:23:25.436] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:69:0)
[18:23:25.436] Timer tick, asking Buildbucket for the build status
[18:23:25.508] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:25.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:70:0) after 1m0s
[18:24:25.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:70:0)
[18:24:25.544] Timer tick, asking Buildbucket for the build status
[18:24:25.619] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:25.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:71:0) after 1m0s
[18:25:25.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:71:0)
[18:25:25.689] Timer tick, asking Buildbucket for the build status
[18:25:25.770] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:25.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:72:0) after 1m0s
[18:26:25.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:72:0)
[18:26:25.823] Timer tick, asking Buildbucket for the build status
[18:26:26.094] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:26.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:73:0) after 1m0s
[18:27:26.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:73:0)
[18:27:26.195] Timer tick, asking Buildbucket for the build status
[18:27:26.312] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:26.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:74:0) after 1m0s
[18:28:26.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:74:0)
[18:28:26.386] Timer tick, asking Buildbucket for the build status
[18:28:26.617] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:26.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:75:0) after 1m0s
[18:29:26.710] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:75:0)
[18:29:26.710] Timer tick, asking Buildbucket for the build status
[18:29:27.118] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:27.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:76:0) after 1m0s
[18:30:27.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:76:0)
[18:30:27.349] Timer tick, asking Buildbucket for the build status
[18:30:27.430] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:27.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:77:0) after 1m0s
[18:31:27.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:77:0)
[18:31:27.429] Timer tick, asking Buildbucket for the build status
[18:31:27.661] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:27.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:78:0) after 1m0s
[18:32:27.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:78:0)
[18:32:27.665] Timer tick, asking Buildbucket for the build status
[18:32:28.537] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:28.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:79:0) after 1m0s
[18:33:28.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:79:0)
[18:33:28.560] Timer tick, asking Buildbucket for the build status
[18:33:28.653] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:28.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:80:0) after 1m0s
[18:34:28.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:80:0)
[18:34:28.707] Timer tick, asking Buildbucket for the build status
[18:34:29.046] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:29.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:81:0) after 1m0s
[18:35:29.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:81:0)
[18:35:29.107] Timer tick, asking Buildbucket for the build status
[18:35:29.187] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:29.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:82:0) after 1m0s
[18:36:29.246] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:82:0)
[18:36:29.246] Timer tick, asking Buildbucket for the build status
[18:36:29.453] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:29.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:83:0) after 1m0s
[18:37:29.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:83:0)
[18:37:29.501] Timer tick, asking Buildbucket for the build status
[18:37:29.638] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:29.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:84:0) after 1m0s
[18:38:29.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:84:0)
[18:38:29.781] Timer tick, asking Buildbucket for the build status
[18:38:29.923] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:29.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:85:0) after 1m0s
[18:39:29.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:85:0)
[18:39:29.985] Timer tick, asking Buildbucket for the build status
[18:39:30.120] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:30.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:86:0) after 1m0s
[18:40:30.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:86:0)
[18:40:30.146] Timer tick, asking Buildbucket for the build status
[18:40:30.227] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:30.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:87:0) after 1m0s
[18:41:30.300] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:87:0)
[18:41:30.300] Timer tick, asking Buildbucket for the build status
[18:41:30.783] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:30.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:88:0) after 1m0s
[18:42:30.776] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:88:0)
[18:42:30.776] Timer tick, asking Buildbucket for the build status
[18:42:30.897] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:30.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:89:0) after 1m0s
[18:43:30.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:89:0)
[18:43:30.926] Timer tick, asking Buildbucket for the build status
[18:43:31.396] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:31.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:90:0) after 1m0s
[18:44:31.462] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:90:0)
[18:44:31.462] Timer tick, asking Buildbucket for the build status
[18:44:31.556] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:31.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:91:0) after 1m0s
[18:45:31.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:91:0)
[18:45:31.594] Timer tick, asking Buildbucket for the build status
[18:45:31.667] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:31.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:92:0) after 1m0s
[18:46:31.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:92:0)
[18:46:31.697] Timer tick, asking Buildbucket for the build status
[18:46:32.230] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:32.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:93:0) after 1m0s
[18:47:32.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:93:0)
[18:47:32.289] Timer tick, asking Buildbucket for the build status
[18:47:32.355] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:32.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:94:0) after 1m0s
[18:48:32.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:94:0)
[18:48:32.572] Timer tick, asking Buildbucket for the build status
[18:48:32.891] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:32.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:95:0) after 1m0s
[18:49:32.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:95:0)
[18:49:32.945] Timer tick, asking Buildbucket for the build status
[18:49:33.807] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:33.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:96:0) after 1m0s
[18:50:33.901] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:96:0)
[18:50:33.901] Timer tick, asking Buildbucket for the build status
[18:50:34.043] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:34.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:97:0) after 1m0s
[18:51:34.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:97:0)
[18:51:34.172] Timer tick, asking Buildbucket for the build status
[18:51:34.385] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:34.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:98:0) after 1m0s
[18:52:34.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:98:0)
[18:52:34.548] Timer tick, asking Buildbucket for the build status
[18:52:34.892] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:34.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:99:0) after 1m0s
[18:53:34.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:99:0)
[18:53:34.899] Timer tick, asking Buildbucket for the build status
[18:53:35.012] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:35.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:100:0) after 1m0s
[18:54:35.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:100:0)
[18:54:35.101] Timer tick, asking Buildbucket for the build status
[18:54:35.172] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:35.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:101:0) after 1m0s
[18:55:35.206] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:101:0)
[18:55:35.206] Timer tick, asking Buildbucket for the build status
[18:55:35.318] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:35.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:102:0) after 1m0s
[18:56:35.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:102:0)
[18:56:35.364] Timer tick, asking Buildbucket for the build status
[18:56:35.625] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:35.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:103:0) after 1m0s
[18:57:35.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:103:0)
[18:57:35.652] Timer tick, asking Buildbucket for the build status
[18:57:35.768] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:35.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:104:0) after 1m0s
[18:58:35.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:104:0)
[18:58:35.811] Timer tick, asking Buildbucket for the build status
[18:58:35.883] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:35.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:105:0) after 1m0s
[18:59:36.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:105:0)
[18:59:36.003] Timer tick, asking Buildbucket for the build status
[18:59:36.077] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:36.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:106:0) after 1m0s
[19:00:36.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:106:0)
[19:00:36.142] Timer tick, asking Buildbucket for the build status
[19:00:36.214] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:36.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:107:0) after 1m0s
[19:01:36.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:107:0)
[19:01:36.245] Timer tick, asking Buildbucket for the build status
[19:01:36.709] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:36.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:108:0) after 1m0s
[19:02:36.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:108:0)
[19:02:36.772] Timer tick, asking Buildbucket for the build status
[19:02:36.875] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:36.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:109:0) after 1m0s
[19:03:36.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:109:0)
[19:03:36.933] Timer tick, asking Buildbucket for the build status
[19:03:37.368] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:37.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:110:0) after 1m0s
[19:04:37.456] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:110:0)
[19:04:37.456] Timer tick, asking Buildbucket for the build status
[19:04:37.521] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:37.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:111:0) after 1m0s
[19:05:37.546] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:111:0)
[19:05:37.546] Timer tick, asking Buildbucket for the build status
[19:05:37.844] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:37.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:112:0) after 1m0s
[19:06:37.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:112:0)
[19:06:37.971] Timer tick, asking Buildbucket for the build status
[19:06:38.174] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:38.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:113:0) after 1m0s
[19:07:38.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:113:0)
[19:07:38.269] Timer tick, asking Buildbucket for the build status
[19:07:38.338] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:38.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:114:0) after 1m0s
[19:08:38.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:114:0)
[19:08:38.368] Timer tick, asking Buildbucket for the build status
[19:08:38.511] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:38.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:115:0) after 1m0s
[19:09:38.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:115:0)
[19:09:38.601] Timer tick, asking Buildbucket for the build status
[19:09:38.884] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:38.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:116:0) after 1m0s
[19:10:38.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:116:0)
[19:10:38.987] Timer tick, asking Buildbucket for the build status
[19:10:39.079] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:39.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:117:0) after 1m0s
[19:11:39.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:117:0)
[19:11:39.093] Timer tick, asking Buildbucket for the build status
[19:11:39.225] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:39.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:118:0) after 1m0s
[19:12:39.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:118:0)
[19:12:39.289] Timer tick, asking Buildbucket for the build status
[19:12:39.359] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:39.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:119:0) after 1m0s
[19:13:39.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:119:0)
[19:13:39.434] Timer tick, asking Buildbucket for the build status
[19:13:39.601] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:39.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:120:0) after 1m0s
[19:14:39.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:120:0)
[19:14:39.795] Timer tick, asking Buildbucket for the build status
[19:14:39.860] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:39.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:121:0) after 1m0s
[19:15:39.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:121:0)
[19:15:39.889] Timer tick, asking Buildbucket for the build status
[19:15:40.193] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:40.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:122:0) after 1m0s
[19:16:40.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:122:0)
[19:16:40.295] Timer tick, asking Buildbucket for the build status
[19:16:40.465] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:40.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:123:0) after 1m0s
[19:17:40.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:123:0)
[19:17:40.484] Timer tick, asking Buildbucket for the build status
[19:17:40.563] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:40.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:124:0) after 1m0s
[19:18:40.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:124:0)
[19:18:40.598] Timer tick, asking Buildbucket for the build status
[19:18:40.935] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:40.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:125:0) after 1m0s
[19:19:40.959] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:125:0)
[19:19:40.959] Timer tick, asking Buildbucket for the build status
[19:19:41.130] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:41.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:126:0) after 1m0s
[19:20:41.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:126:0)
[19:20:41.211] Timer tick, asking Buildbucket for the build status
[19:20:41.331] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:41.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:127:0) after 1m0s
[19:21:41.380] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:127:0)
[19:21:41.380] Timer tick, asking Buildbucket for the build status
[19:21:41.525] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:41.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:128:0) after 1m0s
[19:22:41.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:128:0)
[19:22:41.548] Timer tick, asking Buildbucket for the build status
[19:22:41.633] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:41.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:129:0) after 1m0s
[19:23:41.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:129:0)
[19:23:41.689] Timer tick, asking Buildbucket for the build status
[19:23:41.820] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:41.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:130:0) after 1m0s
[19:24:41.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:130:0)
[19:24:41.957] Timer tick, asking Buildbucket for the build status
[19:24:42.390] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:42.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:131:0) after 1m0s
[19:25:42.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:131:0)
[19:25:42.407] Timer tick, asking Buildbucket for the build status
[19:25:42.788] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:42.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:132:0) after 1m0s
[19:26:42.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:132:0)
[19:26:42.876] Timer tick, asking Buildbucket for the build status
[19:26:43.005] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:43.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:133:0) after 1m0s
[19:27:43.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:133:0)
[19:27:43.042] Timer tick, asking Buildbucket for the build status
[19:27:43.113] Build 8872515401087197328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:43.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037945463750873952:134:0) after 1m0s
[19:28:35.407] Received PubSub notification, asking Buildbucket for the build status
[19:28:35.618] Build 8872515401087197328: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:28:35.618] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1596914914533132",
"created_by": "project:chromium",
"created_ts": "1596906962272880",
"id": "8872515401087197328",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dab549a6c9a8f85ab2ea527e97479767e2f6c240\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-4-74dv\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 289, \"got_angle_revision\": \"48ba75ac6845428971ea2a12bfb69eb85e608409\", \"got_dawn_revision\": \"101a5829d50f9509d514a13d49946a99c8f1c469\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"dab549a6c9a8f85ab2ea527e97479767e2f6c240\", \"got_revision_cp\": \"refs/heads/master@{#796235}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"59b4828f293eb16f8983ae9ea0a8a0a84cf8174c\", \"got_v8_revision\": \"4f52dde736e657ac01cf74a6cdc38c752f9a5dae\", \"got_v8_revision_cp\": \"refs/heads/8.6.337@{#1}\", \"got_webrtc_revision\": \"4c2f9c90f42591f0935b496d61ee256ebf53198f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31887}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dab549a6c9a8f85ab2ea527e97479767e2f6c240\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-4-74dv\"], \"image\": [\"chrome-win10-1703-20073100-2eb11ddbc6e\"], \"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\": [\"5472-d0af7f3\"], \"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": "1596907000292052",
"status": "COMPLETED",
"status_changed_ts": "1596914914977909",
"tags": [
"build_address:luci.chromium.ci/win-official/289",
"builder:win-official",
"buildset:commit/git/dab549a6c9a8f85ab2ea527e97479767e2f6c240",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dab549a6c9a8f85ab2ea527e97479767e2f6c240",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037945463750873952",
"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/8872515401087197328/+/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:4de7e40ca9a13b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1596914915013532",
"url": "https://ci.chromium.org/b/8872515401087197328",
"utcnow_ts": "1596914915602702"
}
}
[19:28:35.618] Invocation finished in 2h12m35.269577569s with status SUCCEEDED