[13:14:19.055] New invocation is queued and will start shortly
[13:14:20.206] Starting the invocation (attempt 1)
[13:14:20.250] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:14:20.250] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:14:20.250] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030078733400601904",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"38f10c19829d8f34ca056fb2ef23ae86e32d4a7c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9030078733400601904",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/38f10c19829d8f34ca056fb2ef23ae86e32d4a7c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38f10c19829d8f34ca056fb2ef23ae86e32d4a7c",
"gitiles_ref:refs/heads/master"
]
}
[13:14:21.012] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604409260387276",
"id": "8864648671338329936",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"38f10c19829d8f34ca056fb2ef23ae86e32d4a7c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604409260877736",
"tags": [
"build_address:luci.chromium.ci/win-official/953",
"builder:win-official",
"buildset:commit/git/38f10c19829d8f34ca056fb2ef23ae86e32d4a7c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38f10c19829d8f34ca056fb2ef23ae86e32d4a7c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030078733400601904",
"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/8864648671338329936/+/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": "1604409260877851",
"url": "https://ci.chromium.org/b/8864648671338329936",
"utcnow_ts": "1604409261002595"
}
}
[13:14:21.012] Task URL: https://ci.chromium.org/b/8864648671338329936
[13:14:21.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:2:0) after 1m0s
[13:14:50.149] Received PubSub notification, asking Buildbucket for the build status
[13:14:50.236] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:21.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:2:0)
[13:15:21.677] Timer tick, asking Buildbucket for the build status
[13:15:21.780] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:21.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:4:0) after 1m0s
[13:16:21.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:4:0)
[13:16:21.889] Timer tick, asking Buildbucket for the build status
[13:16:22.242] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:22.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:5:0) after 1m0s
[13:17:22.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:5:0)
[13:17:22.413] Timer tick, asking Buildbucket for the build status
[13:17:22.633] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:22.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:6:0) after 1m0s
[13:18:22.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:6:0)
[13:18:22.756] Timer tick, asking Buildbucket for the build status
[13:18:22.861] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:22.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:7:0) after 1m0s
[13:19:22.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:7:0)
[13:19:22.883] Timer tick, asking Buildbucket for the build status
[13:19:23.002] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:23.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:8:0) after 1m0s
[13:20:23.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:8:0)
[13:20:23.067] Timer tick, asking Buildbucket for the build status
[13:20:23.180] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:23.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:9:0) after 1m0s
[13:21:23.252] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:9:0)
[13:21:23.252] Timer tick, asking Buildbucket for the build status
[13:21:23.334] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:23.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:10:0) after 1m0s
[13:22:23.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:10:0)
[13:22:23.403] Timer tick, asking Buildbucket for the build status
[13:22:23.882] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:23.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:11:0) after 1m0s
[13:23:23.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:11:0)
[13:23:23.926] Timer tick, asking Buildbucket for the build status
[13:23:24.194] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:24.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:12:0) after 1m0s
[13:24:24.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:12:0)
[13:24:24.225] Timer tick, asking Buildbucket for the build status
[13:24:24.488] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:24.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:13:0) after 1m0s
[13:25:24.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:13:0)
[13:25:24.521] Timer tick, asking Buildbucket for the build status
[13:25:25.058] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:25.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:14:0) after 1m0s
[13:26:25.308] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:14:0)
[13:26:25.308] Timer tick, asking Buildbucket for the build status
[13:26:25.435] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:25.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:15:0) after 1m0s
[13:27:25.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:15:0)
[13:27:25.650] Timer tick, asking Buildbucket for the build status
[13:27:25.868] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:25.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:16:0) after 1m0s
[13:28:25.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:16:0)
[13:28:25.925] Timer tick, asking Buildbucket for the build status
[13:28:26.007] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:26.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:17:0) after 1m0s
[13:29:26.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:17:0)
[13:29:26.094] Timer tick, asking Buildbucket for the build status
[13:29:26.236] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:26.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:18:0) after 1m0s
[13:30:26.294] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:18:0)
[13:30:26.294] Timer tick, asking Buildbucket for the build status
[13:30:26.363] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:26.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:19:0) after 1m0s
[13:31:26.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:19:0)
[13:31:26.434] Timer tick, asking Buildbucket for the build status
[13:31:26.513] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:26.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:20:0) after 1m0s
[13:32:26.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:20:0)
[13:32:26.601] Timer tick, asking Buildbucket for the build status
[13:32:26.798] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:26.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:21:0) after 1m0s
[13:33:26.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:21:0)
[13:33:26.828] Timer tick, asking Buildbucket for the build status
[13:33:27.118] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:27.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:22:0) after 1m0s
[13:34:27.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:22:0)
[13:34:27.144] Timer tick, asking Buildbucket for the build status
[13:34:27.257] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:27.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:23:0) after 1m0s
[13:35:27.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:23:0)
[13:35:27.279] Timer tick, asking Buildbucket for the build status
[13:35:27.395] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:27.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:24:0) after 1m0s
[13:36:27.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:24:0)
[13:36:27.442] Timer tick, asking Buildbucket for the build status
[13:36:27.611] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:27.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:25:0) after 1m0s
[13:37:27.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:25:0)
[13:37:27.675] Timer tick, asking Buildbucket for the build status
[13:37:27.749] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:27.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:26:0) after 1m0s
[13:38:27.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:26:0)
[13:38:27.786] Timer tick, asking Buildbucket for the build status
[13:38:28.087] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:28.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:27:0) after 1m0s
[13:39:28.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:27:0)
[13:39:28.117] Timer tick, asking Buildbucket for the build status
[13:39:28.220] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:28.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:28:0) after 1m0s
[13:40:28.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:28:0)
[13:40:28.287] Timer tick, asking Buildbucket for the build status
[13:40:28.368] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:28.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:29:0) after 1m0s
[13:41:28.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:29:0)
[13:41:28.416] Timer tick, asking Buildbucket for the build status
[13:41:28.523] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:28.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:30:0) after 1m0s
[13:42:28.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:30:0)
[13:42:28.544] Timer tick, asking Buildbucket for the build status
[13:42:28.642] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:28.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:31:0) after 1m0s
[13:43:28.714] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:31:0)
[13:43:28.714] Timer tick, asking Buildbucket for the build status
[13:43:28.771] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:28.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:32:0) after 1m0s
[13:44:28.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:32:0)
[13:44:28.802] Timer tick, asking Buildbucket for the build status
[13:44:28.915] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:28.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:33:0) after 1m0s
[13:45:29.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:33:0)
[13:45:29.019] Timer tick, asking Buildbucket for the build status
[13:45:29.703] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:29.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:34:0) after 1m0s
[13:46:29.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:34:0)
[13:46:29.742] Timer tick, asking Buildbucket for the build status
[13:46:29.834] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:29.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:35:0) after 1m0s
[13:47:29.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:35:0)
[13:47:29.856] Timer tick, asking Buildbucket for the build status
[13:47:29.943] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:29.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:36:0) after 1m0s
[13:48:30.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:36:0)
[13:48:30.057] Timer tick, asking Buildbucket for the build status
[13:48:30.223] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:30.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:37:0) after 1m0s
[13:49:30.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:37:0)
[13:49:30.261] Timer tick, asking Buildbucket for the build status
[13:49:30.393] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:30.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:38:0) after 1m0s
[13:50:30.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:38:0)
[13:50:30.464] Timer tick, asking Buildbucket for the build status
[13:50:30.633] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:30.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:39:0) after 1m0s
[13:51:30.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:39:0)
[13:51:30.655] Timer tick, asking Buildbucket for the build status
[13:51:30.823] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:30.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:40:0) after 1m0s
[13:52:31.070] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:40:0)
[13:52:31.070] Timer tick, asking Buildbucket for the build status
[13:52:31.153] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:31.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:41:0) after 1m0s
[13:53:31.301] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:41:0)
[13:53:31.301] Timer tick, asking Buildbucket for the build status
[13:53:31.375] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:31.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:42:0) after 1m0s
[13:54:31.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:42:0)
[13:54:31.484] Timer tick, asking Buildbucket for the build status
[13:54:31.641] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:31.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:43:0) after 1m0s
[13:55:31.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:43:0)
[13:55:31.654] Timer tick, asking Buildbucket for the build status
[13:55:31.742] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:31.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:44:0) after 1m0s
[13:56:31.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:44:0)
[13:56:31.788] Timer tick, asking Buildbucket for the build status
[13:56:31.867] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:31.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:45:0) after 1m0s
[13:57:31.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:45:0)
[13:57:31.924] Timer tick, asking Buildbucket for the build status
[13:57:32.132] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:32.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:46:0) after 1m0s
[13:58:32.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:46:0)
[13:58:32.146] Timer tick, asking Buildbucket for the build status
[13:58:32.979] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:32.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:47:0) after 1m0s
[13:59:33.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:47:0)
[13:59:33.058] Timer tick, asking Buildbucket for the build status
[13:59:33.328] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:33.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:48:0) after 1m0s
[14:00:33.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:48:0)
[14:00:33.354] Timer tick, asking Buildbucket for the build status
[14:00:33.521] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:33.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:49:0) after 1m0s
[14:01:33.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:49:0)
[14:01:33.593] Timer tick, asking Buildbucket for the build status
[14:01:33.676] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:33.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:50:0) after 1m0s
[14:02:33.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:50:0)
[14:02:33.665] Timer tick, asking Buildbucket for the build status
[14:02:33.752] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:33.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:51:0) after 1m0s
[14:03:33.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:51:0)
[14:03:33.799] Timer tick, asking Buildbucket for the build status
[14:03:33.889] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:33.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:52:0) after 1m0s
[14:04:33.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:52:0)
[14:04:33.905] Timer tick, asking Buildbucket for the build status
[14:04:34.026] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:34.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:53:0) after 1m0s
[14:05:34.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:53:0)
[14:05:34.076] Timer tick, asking Buildbucket for the build status
[14:05:34.157] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:34.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:54:0) after 1m0s
[14:06:34.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:54:0)
[14:06:34.217] Timer tick, asking Buildbucket for the build status
[14:06:34.289] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:34.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:55:0) after 1m0s
[14:07:34.370] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:55:0)
[14:07:34.370] Timer tick, asking Buildbucket for the build status
[14:07:34.464] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:34.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:56:0) after 1m0s
[14:08:34.576] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:56:0)
[14:08:34.576] Timer tick, asking Buildbucket for the build status
[14:08:34.705] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:34.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:57:0) after 1m0s
[14:09:34.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:57:0)
[14:09:34.786] Timer tick, asking Buildbucket for the build status
[14:09:34.943] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:34.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:58:0) after 1m0s
[14:10:35.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:58:0)
[14:10:35.083] Timer tick, asking Buildbucket for the build status
[14:10:35.227] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:35.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:59:0) after 1m0s
[14:11:35.265] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:59:0)
[14:11:35.265] Timer tick, asking Buildbucket for the build status
[14:11:35.454] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:35.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:60:0) after 1m0s
[14:12:35.500] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:60:0)
[14:12:35.500] Timer tick, asking Buildbucket for the build status
[14:12:35.589] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:35.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:61:0) after 1m0s
[14:13:35.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:61:0)
[14:13:35.618] Timer tick, asking Buildbucket for the build status
[14:13:36.787] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:36.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:62:0) after 1m0s
[14:14:36.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:62:0)
[14:14:36.849] Timer tick, asking Buildbucket for the build status
[14:14:37.316] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:37.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:63:0) after 1m0s
[14:15:37.333] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:63:0)
[14:15:37.333] Timer tick, asking Buildbucket for the build status
[14:15:37.454] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:37.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:64:0) after 1m0s
[14:16:37.456] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:64:0)
[14:16:37.456] Timer tick, asking Buildbucket for the build status
[14:16:37.695] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:37.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:65:0) after 1m0s
[14:17:37.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:65:0)
[14:17:37.811] Timer tick, asking Buildbucket for the build status
[14:17:37.892] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:37.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:66:0) after 1m0s
[14:18:37.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:66:0)
[14:18:37.940] Timer tick, asking Buildbucket for the build status
[14:18:38.092] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:38.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:67:0) after 1m0s
[14:19:38.128] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:67:0)
[14:19:38.128] Timer tick, asking Buildbucket for the build status
[14:19:38.272] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:38.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:68:0) after 1m0s
[14:20:38.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:68:0)
[14:20:38.326] Timer tick, asking Buildbucket for the build status
[14:20:38.416] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:38.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:69:0) after 1m0s
[14:21:38.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:69:0)
[14:21:38.537] Timer tick, asking Buildbucket for the build status
[14:21:38.836] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:38.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:70:0) after 1m0s
[14:22:38.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:70:0)
[14:22:38.872] Timer tick, asking Buildbucket for the build status
[14:22:39.744] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:39.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:71:0) after 1m0s
[14:23:39.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:71:0)
[14:23:39.832] Timer tick, asking Buildbucket for the build status
[14:23:39.918] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:39.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:72:0) after 1m0s
[14:24:39.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:72:0)
[14:24:39.987] Timer tick, asking Buildbucket for the build status
[14:24:41.239] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:41.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:73:0) after 1m0s
[14:25:41.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:73:0)
[14:25:41.345] Timer tick, asking Buildbucket for the build status
[14:25:41.436] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:41.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:74:0) after 1m0s
[14:26:41.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:74:0)
[14:26:41.677] Timer tick, asking Buildbucket for the build status
[14:26:41.785] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:41.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:75:0) after 1m0s
[14:27:41.769] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:75:0)
[14:27:41.769] Timer tick, asking Buildbucket for the build status
[14:27:41.895] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:41.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:76:0) after 1m0s
[14:28:41.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:76:0)
[14:28:41.945] Timer tick, asking Buildbucket for the build status
[14:28:42.141] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:42.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:77:0) after 1m0s
[14:29:42.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:77:0)
[14:29:42.207] Timer tick, asking Buildbucket for the build status
[14:29:42.394] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:42.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:78:0) after 1m0s
[14:30:42.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:78:0)
[14:30:42.417] Timer tick, asking Buildbucket for the build status
[14:30:42.694] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:42.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:79:0) after 1m0s
[14:31:42.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:79:0)
[14:31:42.750] Timer tick, asking Buildbucket for the build status
[14:31:42.861] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:42.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:80:0) after 1m0s
[14:32:42.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:80:0)
[14:32:42.837] Timer tick, asking Buildbucket for the build status
[14:32:42.943] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:42.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:81:0) after 1m0s
[14:33:42.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:81:0)
[14:33:42.974] Timer tick, asking Buildbucket for the build status
[14:33:43.184] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:43.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:82:0) after 1m0s
[14:34:43.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:82:0)
[14:34:43.221] Timer tick, asking Buildbucket for the build status
[14:34:43.369] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:43.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:83:0) after 1m0s
[14:35:43.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:83:0)
[14:35:43.466] Timer tick, asking Buildbucket for the build status
[14:35:43.625] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:43.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:84:0) after 1m0s
[14:36:43.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:84:0)
[14:36:43.686] Timer tick, asking Buildbucket for the build status
[14:36:44.408] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:44.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:85:0) after 1m0s
[14:37:44.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:85:0)
[14:37:44.488] Timer tick, asking Buildbucket for the build status
[14:37:44.571] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:44.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:86:0) after 1m0s
[14:38:44.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:86:0)
[14:38:44.692] Timer tick, asking Buildbucket for the build status
[14:38:44.931] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:44.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:87:0) after 1m0s
[14:39:45.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:87:0)
[14:39:45.037] Timer tick, asking Buildbucket for the build status
[14:39:45.362] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:45.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:88:0) after 1m0s
[14:40:45.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:88:0)
[14:40:45.389] Timer tick, asking Buildbucket for the build status
[14:40:45.522] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:45.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:89:0) after 1m0s
[14:41:45.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:89:0)
[14:41:45.566] Timer tick, asking Buildbucket for the build status
[14:41:45.787] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:45.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:90:0) after 1m0s
[14:42:45.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:90:0)
[14:42:45.820] Timer tick, asking Buildbucket for the build status
[14:42:45.954] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:45.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:91:0) after 1m0s
[14:43:46.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:91:0)
[14:43:46.019] Timer tick, asking Buildbucket for the build status
[14:43:46.101] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:46.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:92:0) after 1m0s
[14:44:46.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:92:0)
[14:44:46.151] Timer tick, asking Buildbucket for the build status
[14:44:46.332] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:46.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:93:0) after 1m0s
[14:45:46.381] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:93:0)
[14:45:46.381] Timer tick, asking Buildbucket for the build status
[14:45:46.748] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:46.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:94:0) after 1m0s
[14:46:46.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:94:0)
[14:46:46.886] Timer tick, asking Buildbucket for the build status
[14:46:47.057] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:47.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:95:0) after 1m0s
[14:47:47.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:95:0)
[14:47:47.218] Timer tick, asking Buildbucket for the build status
[14:47:47.316] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:47.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:96:0) after 1m0s
[14:48:47.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:96:0)
[14:48:47.444] Timer tick, asking Buildbucket for the build status
[14:48:47.702] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:47.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:97:0) after 1m0s
[14:49:47.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:97:0)
[14:49:47.892] Timer tick, asking Buildbucket for the build status
[14:49:47.996] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:47.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:98:0) after 1m0s
[14:50:48.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:98:0)
[14:50:48.066] Timer tick, asking Buildbucket for the build status
[14:50:48.133] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:48.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:99:0) after 1m0s
[14:51:48.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:99:0)
[14:51:48.227] Timer tick, asking Buildbucket for the build status
[14:51:48.334] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:48.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:100:0) after 1m0s
[14:52:48.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:100:0)
[14:52:48.356] Timer tick, asking Buildbucket for the build status
[14:52:48.919] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:48.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:101:0) after 1m0s
[14:53:49.031] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:101:0)
[14:53:49.031] Timer tick, asking Buildbucket for the build status
[14:53:49.097] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:49.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:102:0) after 1m0s
[14:54:49.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:102:0)
[14:54:49.112] Timer tick, asking Buildbucket for the build status
[14:54:49.256] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:49.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:103:0) after 1m0s
[14:55:49.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:103:0)
[14:55:49.337] Timer tick, asking Buildbucket for the build status
[14:55:49.523] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:49.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:104:0) after 1m0s
[14:56:49.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:104:0)
[14:56:49.555] Timer tick, asking Buildbucket for the build status
[14:56:49.625] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:49.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:105:0) after 1m0s
[14:57:49.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:105:0)
[14:57:49.656] Timer tick, asking Buildbucket for the build status
[14:57:49.756] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:49.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:106:0) after 1m0s
[14:58:49.852] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:106:0)
[14:58:49.852] Timer tick, asking Buildbucket for the build status
[14:58:49.920] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:49.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:107:0) after 1m0s
[14:59:49.996] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:107:0)
[14:59:49.996] Timer tick, asking Buildbucket for the build status
[14:59:50.198] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:50.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:108:0) after 1m0s
[15:00:50.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:108:0)
[15:00:50.239] Timer tick, asking Buildbucket for the build status
[15:00:50.504] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:50.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:109:0) after 1m0s
[15:01:50.541] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:109:0)
[15:01:50.541] Timer tick, asking Buildbucket for the build status
[15:01:50.679] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:50.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:110:0) after 1m0s
[15:02:50.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:110:0)
[15:02:50.704] Timer tick, asking Buildbucket for the build status
[15:02:50.873] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:50.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:111:0) after 1m0s
[15:03:50.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:111:0)
[15:03:50.870] Timer tick, asking Buildbucket for the build status
[15:03:51.141] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:51.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:112:0) after 1m0s
[15:04:51.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:112:0)
[15:04:51.152] Timer tick, asking Buildbucket for the build status
[15:04:51.611] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:51.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:113:0) after 1m0s
[15:05:51.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:113:0)
[15:05:51.592] Timer tick, asking Buildbucket for the build status
[15:05:52.323] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:52.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:114:0) after 1m0s
[15:06:52.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:114:0)
[15:06:52.361] Timer tick, asking Buildbucket for the build status
[15:06:52.540] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:52.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:115:0) after 1m0s
[15:07:52.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:115:0)
[15:07:52.599] Timer tick, asking Buildbucket for the build status
[15:07:52.732] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:52.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:116:0) after 1m0s
[15:08:52.778] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:116:0)
[15:08:52.778] Timer tick, asking Buildbucket for the build status
[15:08:52.847] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:52.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:117:0) after 1m0s
[15:09:52.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:117:0)
[15:09:52.879] Timer tick, asking Buildbucket for the build status
[15:09:53.054] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:53.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:118:0) after 1m0s
[15:10:53.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:118:0)
[15:10:53.062] Timer tick, asking Buildbucket for the build status
[15:10:53.157] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:53.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:119:0) after 1m0s
[15:11:53.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:119:0)
[15:11:53.268] Timer tick, asking Buildbucket for the build status
[15:11:53.362] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:53.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:120:0) after 1m0s
[15:12:53.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:120:0)
[15:12:53.413] Timer tick, asking Buildbucket for the build status
[15:12:53.552] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:53.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:121:0) after 1m0s
[15:13:53.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:121:0)
[15:13:53.572] Timer tick, asking Buildbucket for the build status
[15:13:53.790] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:53.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:122:0) after 1m0s
[15:14:53.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:122:0)
[15:14:53.887] Timer tick, asking Buildbucket for the build status
[15:14:54.315] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:54.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:123:0) after 1m0s
[15:15:54.343] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:123:0)
[15:15:54.343] Timer tick, asking Buildbucket for the build status
[15:15:54.421] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:54.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:124:0) after 1m0s
[15:16:54.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:124:0)
[15:16:54.588] Timer tick, asking Buildbucket for the build status
[15:16:55.036] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:55.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:125:0) after 1m0s
[15:17:55.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:125:0)
[15:17:55.125] Timer tick, asking Buildbucket for the build status
[15:17:56.656] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:56.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:126:0) after 1m0s
[15:18:56.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:126:0)
[15:18:56.705] Timer tick, asking Buildbucket for the build status
[15:18:56.817] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:56.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:127:0) after 1m0s
[15:19:56.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:127:0)
[15:19:56.820] Timer tick, asking Buildbucket for the build status
[15:19:56.966] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:56.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:128:0) after 1m0s
[15:20:57.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:128:0)
[15:20:57.038] Timer tick, asking Buildbucket for the build status
[15:20:57.267] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:57.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:129:0) after 1m0s
[15:21:57.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:129:0)
[15:21:57.303] Timer tick, asking Buildbucket for the build status
[15:21:57.397] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:57.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:130:0) after 1m0s
[15:22:57.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:130:0)
[15:22:57.431] Timer tick, asking Buildbucket for the build status
[15:22:57.861] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:57.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:131:0) after 1m0s
[15:23:57.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:131:0)
[15:23:57.936] Timer tick, asking Buildbucket for the build status
[15:23:58.285] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:58.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:132:0) after 1m0s
[15:24:58.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:132:0)
[15:24:58.368] Timer tick, asking Buildbucket for the build status
[15:24:58.538] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:58.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:133:0) after 1m0s
[15:25:58.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:133:0)
[15:25:58.619] Timer tick, asking Buildbucket for the build status
[15:25:58.710] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:58.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:134:0) after 1m0s
[15:26:58.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:134:0)
[15:26:58.833] Timer tick, asking Buildbucket for the build status
[15:26:58.924] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:58.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:135:0) after 1m0s
[15:27:59.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:135:0)
[15:27:59.065] Timer tick, asking Buildbucket for the build status
[15:27:59.525] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:59.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:136:0) after 1m0s
[15:28:59.581] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:136:0)
[15:28:59.581] Timer tick, asking Buildbucket for the build status
[15:28:59.662] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:59.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:137:0) after 1m0s
[15:29:59.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:137:0)
[15:29:59.692] Timer tick, asking Buildbucket for the build status
[15:30:00.160] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:00.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:138:0) after 1m0s
[15:31:00.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:138:0)
[15:31:00.217] Timer tick, asking Buildbucket for the build status
[15:31:00.328] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:00.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:139:0) after 1m0s
[15:32:00.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:139:0)
[15:32:00.398] Timer tick, asking Buildbucket for the build status
[15:32:00.623] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:00.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:140:0) after 1m0s
[15:33:00.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:140:0)
[15:33:00.739] Timer tick, asking Buildbucket for the build status
[15:33:00.937] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:00.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:141:0) after 1m0s
[15:34:01.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:141:0)
[15:34:01.022] Timer tick, asking Buildbucket for the build status
[15:34:01.183] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:01.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:142:0) after 1m0s
[15:35:01.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:142:0)
[15:35:01.263] Timer tick, asking Buildbucket for the build status
[15:35:01.598] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:01.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:143:0) after 1m0s
[15:36:01.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:143:0)
[15:36:01.718] Timer tick, asking Buildbucket for the build status
[15:36:01.817] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:01.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:144:0) after 1m0s
[15:37:01.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:144:0)
[15:37:01.856] Timer tick, asking Buildbucket for the build status
[15:37:01.951] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:01.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:145:0) after 1m0s
[15:38:01.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:145:0)
[15:38:01.999] Timer tick, asking Buildbucket for the build status
[15:38:02.460] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:02.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:146:0) after 1m0s
[15:39:02.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:146:0)
[15:39:02.503] Timer tick, asking Buildbucket for the build status
[15:39:02.630] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:02.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:147:0) after 1m0s
[15:40:02.714] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:147:0)
[15:40:02.714] Timer tick, asking Buildbucket for the build status
[15:40:03.295] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:03.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:148:0) after 1m0s
[15:41:03.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:148:0)
[15:41:03.350] Timer tick, asking Buildbucket for the build status
[15:41:03.629] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:03.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:149:0) after 1m0s
[15:42:03.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:149:0)
[15:42:03.677] Timer tick, asking Buildbucket for the build status
[15:42:03.765] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:03.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:150:0) after 1m0s
[15:43:03.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:150:0)
[15:43:03.856] Timer tick, asking Buildbucket for the build status
[15:43:04.072] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:04.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:151:0) after 1m0s
[15:44:04.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:151:0)
[15:44:04.107] Timer tick, asking Buildbucket for the build status
[15:44:04.429] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:04.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:152:0) after 1m0s
[15:45:04.445] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:152:0)
[15:45:04.445] Timer tick, asking Buildbucket for the build status
[15:45:04.723] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:04.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:153:0) after 1m0s
[15:46:04.755] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:153:0)
[15:46:04.755] Timer tick, asking Buildbucket for the build status
[15:46:04.826] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:04.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:154:0) after 1m0s
[15:47:04.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:154:0)
[15:47:04.842] Timer tick, asking Buildbucket for the build status
[15:47:04.935] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:04.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:155:0) after 1m0s
[15:48:05.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:155:0)
[15:48:05.065] Timer tick, asking Buildbucket for the build status
[15:48:05.182] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:05.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:156:0) after 1m0s
[15:49:05.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:156:0)
[15:49:05.260] Timer tick, asking Buildbucket for the build status
[15:49:05.366] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:05.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:157:0) after 1m0s
[15:50:05.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:157:0)
[15:50:05.412] Timer tick, asking Buildbucket for the build status
[15:50:05.543] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:05.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:158:0) after 1m0s
[15:51:05.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:158:0)
[15:51:05.803] Timer tick, asking Buildbucket for the build status
[15:51:05.877] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:05.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:159:0) after 1m0s
[15:52:06.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:159:0)
[15:52:06.022] Timer tick, asking Buildbucket for the build status
[15:52:06.272] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:06.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:160:0) after 1m0s
[15:53:06.450] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:160:0)
[15:53:06.450] Timer tick, asking Buildbucket for the build status
[15:53:06.509] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:06.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:161:0) after 1m0s
[15:54:06.625] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:161:0)
[15:54:06.625] Timer tick, asking Buildbucket for the build status
[15:54:06.733] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:06.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:162:0) after 1m0s
[15:55:06.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:162:0)
[15:55:06.799] Timer tick, asking Buildbucket for the build status
[15:55:06.921] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:06.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:163:0) after 1m0s
[15:56:06.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:163:0)
[15:56:06.949] Timer tick, asking Buildbucket for the build status
[15:56:07.142] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:07.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:164:0) after 1m0s
[15:57:07.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:164:0)
[15:57:07.152] Timer tick, asking Buildbucket for the build status
[15:57:07.262] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:07.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:165:0) after 1m0s
[15:58:07.311] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:165:0)
[15:58:07.311] Timer tick, asking Buildbucket for the build status
[15:58:07.583] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:07.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:166:0) after 1m0s
[15:59:07.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:166:0)
[15:59:07.738] Timer tick, asking Buildbucket for the build status
[15:59:07.818] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:07.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:167:0) after 1m0s
[16:00:08.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:167:0)
[16:00:08.016] Timer tick, asking Buildbucket for the build status
[16:00:08.517] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:08.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:168:0) after 1m0s
[16:01:09.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:168:0)
[16:01:09.832] Timer tick, asking Buildbucket for the build status
[16:01:10.129] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:10.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:169:0) after 1m0s
[16:02:10.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:169:0)
[16:02:10.501] Timer tick, asking Buildbucket for the build status
[16:02:10.713] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:10.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:170:0) after 1m0s
[16:03:10.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:170:0)
[16:03:10.757] Timer tick, asking Buildbucket for the build status
[16:03:10.861] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:10.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:171:0) after 1m0s
[16:04:11.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:171:0)
[16:04:11.036] Timer tick, asking Buildbucket for the build status
[16:04:11.480] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:11.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:172:0) after 1m0s
[16:05:11.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:172:0)
[16:05:11.770] Timer tick, asking Buildbucket for the build status
[16:05:11.906] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:11.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:173:0) after 1m0s
[16:06:12.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:173:0)
[16:06:12.025] Timer tick, asking Buildbucket for the build status
[16:06:12.247] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:12.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:174:0) after 1m0s
[16:07:12.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:174:0)
[16:07:12.362] Timer tick, asking Buildbucket for the build status
[16:07:12.709] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:12.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:175:0) after 1m0s
[16:08:12.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:175:0)
[16:08:12.798] Timer tick, asking Buildbucket for the build status
[16:08:12.879] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:12.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:176:0) after 1m0s
[16:09:12.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:176:0)
[16:09:12.909] Timer tick, asking Buildbucket for the build status
[16:09:12.987] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:12.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:177:0) after 1m0s
[16:10:13.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:177:0)
[16:10:13.141] Timer tick, asking Buildbucket for the build status
[16:10:13.268] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:13.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:178:0) after 1m0s
[16:11:13.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:178:0)
[16:11:13.392] Timer tick, asking Buildbucket for the build status
[16:11:13.466] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:13.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:179:0) after 1m0s
[16:12:13.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:179:0)
[16:12:13.613] Timer tick, asking Buildbucket for the build status
[16:12:13.788] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:13.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:180:0) after 1m0s
[16:13:13.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:180:0)
[16:13:13.780] Timer tick, asking Buildbucket for the build status
[16:13:14.331] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:14.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:181:0) after 1m0s
[16:14:14.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:181:0)
[16:14:14.454] Timer tick, asking Buildbucket for the build status
[16:14:14.723] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:14.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:182:0) after 1m0s
[16:15:14.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:182:0)
[16:15:14.818] Timer tick, asking Buildbucket for the build status
[16:15:15.013] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:15.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:183:0) after 1m0s
[16:16:15.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:183:0)
[16:16:15.079] Timer tick, asking Buildbucket for the build status
[16:16:15.452] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:15.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:184:0) after 1m0s
[16:17:15.462] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:184:0)
[16:17:15.462] Timer tick, asking Buildbucket for the build status
[16:17:15.602] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:15.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:185:0) after 1m0s
[16:18:15.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:185:0)
[16:18:15.636] Timer tick, asking Buildbucket for the build status
[16:18:15.963] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:15.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:186:0) after 1m0s
[16:19:16.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:186:0)
[16:19:16.072] Timer tick, asking Buildbucket for the build status
[16:19:16.267] Build 8864648671338329936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:16.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030078733400601904:187:0) after 1m0s
[16:20:11.356] Received PubSub notification, asking Buildbucket for the build status
[16:20:11.446] Build 8864648671338329936: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:20:11.446] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604420410563284",
"created_by": "project:chromium",
"created_ts": "1604409260387276",
"id": "8864648671338329936",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"38f10c19829d8f34ca056fb2ef23ae86e32d4a7c\"}}",
"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\": \"38f10c19829d8f34ca056fb2ef23ae86e32d4a7c\", \"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-0-2d0n\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 953, \"got_angle_revision\": \"ed916ddf61cc0021cebf55fb6901ba71fe9e6a2e\", \"got_dawn_revision\": \"43ef0a365b8443226916c090b88b3a0b09d6df96\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"38f10c19829d8f34ca056fb2ef23ae86e32d4a7c\", \"got_revision_cp\": \"refs/heads/master@{#823514}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"b5bf82693f85b53ce31846705d323764a582489b\", \"got_v8_revision\": \"e0ff8ffa26e56bf39b22eea7731e1b434d2d7378\", \"got_v8_revision_cp\": \"refs/heads/8.8.219@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"38f10c19829d8f34ca056fb2ef23ae86e32d4a7c\"}, \"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-0-2d0n\"], \"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\": [\"5614-d862eaa\"], \"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": "1604409289507953",
"status": "COMPLETED",
"status_changed_ts": "1604420411052704",
"tags": [
"build_address:luci.chromium.ci/win-official/953",
"builder:win-official",
"buildset:commit/git/38f10c19829d8f34ca056fb2ef23ae86e32d4a7c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38f10c19829d8f34ca056fb2ef23ae86e32d4a7c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030078733400601904",
"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/8864648671338329936/+/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:4fa71004fe6fc810",
"user_agent:luci-scheduler"
],
"updated_ts": "1604420411071777",
"url": "https://ci.chromium.org/b/8864648671338329936",
"utcnow_ts": "1604420411435774"
}
}
[16:20:11.446] Invocation finished in 3h5m52.408748391s with status SUCCEEDED