[04:31:32.732] New invocation is queued and will start shortly
[04:31:33.792] Starting the invocation (attempt 1)
[04:31:33.855] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:31:33.855] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:31:33.855] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022501478199268512",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ef43b16d47e35d079cf15b48c8cd764df940fe12\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9022501478199268512",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"gitiles_ref:refs/heads/master"
]
}
[04:31:34.503] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611635494016888",
"id": "8857071416184976480",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef43b16d47e35d079cf15b48c8cd764df940fe12\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611635494383072",
"tags": [
"build_address:luci.chromium.ci/win-official/1465",
"builder:win-official",
"buildset:commit/git/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022501478199268512",
"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/8857071416184976480/+/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": "1611635494383219",
"url": "https://ci.chromium.org/b/8857071416184976480",
"utcnow_ts": "1611635494494596"
}
}
[04:31:34.503] Task URL: https://ci.chromium.org/b/8857071416184976480
[04:31:34.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:2:0) after 1m0s
[04:32:22.357] Received PubSub notification, asking Buildbucket for the build status
[04:32:22.526] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:34.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:2:0)
[04:32:34.486] Timer tick, asking Buildbucket for the build status
[04:32:34.565] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:34.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:4:0) after 1m0s
[04:33:34.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:4:0)
[04:33:34.613] Timer tick, asking Buildbucket for the build status
[04:33:34.717] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:34.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:5:0) after 1m0s
[04:34:34.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:5:0)
[04:34:34.757] Timer tick, asking Buildbucket for the build status
[04:34:35.045] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:35.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:6:0) after 1m0s
[04:35:35.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:6:0)
[04:35:35.074] Timer tick, asking Buildbucket for the build status
[04:35:35.163] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:35.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:7:0) after 1m0s
[04:36:35.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:7:0)
[04:36:35.238] Timer tick, asking Buildbucket for the build status
[04:36:35.305] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:35.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:8:0) after 1m0s
[04:37:35.330] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:8:0)
[04:37:35.330] Timer tick, asking Buildbucket for the build status
[04:37:35.475] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:35.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:9:0) after 1m0s
[04:38:35.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:9:0)
[04:38:35.529] Timer tick, asking Buildbucket for the build status
[04:38:35.676] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:35.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:10:0) after 1m0s
[04:39:35.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:10:0)
[04:39:35.757] Timer tick, asking Buildbucket for the build status
[04:39:36.026] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:36.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:11:0) after 1m0s
[04:40:36.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:11:0)
[04:40:36.069] Timer tick, asking Buildbucket for the build status
[04:40:36.355] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:36.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:12:0) after 1m0s
[04:41:36.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:12:0)
[04:41:36.378] Timer tick, asking Buildbucket for the build status
[04:41:36.690] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:36.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:13:0) after 1m0s
[04:42:36.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:13:0)
[04:42:36.761] Timer tick, asking Buildbucket for the build status
[04:42:36.854] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:36.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:14:0) after 1m0s
[04:43:36.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:14:0)
[04:43:36.948] Timer tick, asking Buildbucket for the build status
[04:43:37.040] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:37.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:15:0) after 1m0s
[04:44:37.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:15:0)
[04:44:37.083] Timer tick, asking Buildbucket for the build status
[04:44:37.677] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:37.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:16:0) after 1m0s
[04:45:37.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:16:0)
[04:45:37.695] Timer tick, asking Buildbucket for the build status
[04:45:37.788] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:37.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:17:0) after 1m0s
[04:46:37.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:17:0)
[04:46:37.837] Timer tick, asking Buildbucket for the build status
[04:46:37.939] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:37.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:18:0) after 1m0s
[04:47:37.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:18:0)
[04:47:37.942] Timer tick, asking Buildbucket for the build status
[04:47:38.104] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:38.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:19:0) after 1m0s
[04:48:38.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:19:0)
[04:48:38.145] Timer tick, asking Buildbucket for the build status
[04:48:38.200] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:38.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:20:0) after 1m0s
[04:49:38.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:20:0)
[04:49:38.615] Timer tick, asking Buildbucket for the build status
[04:49:38.688] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:38.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:21:0) after 1m0s
[04:50:38.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:21:0)
[04:50:38.898] Timer tick, asking Buildbucket for the build status
[04:50:38.974] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:38.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:22:0) after 1m0s
[04:51:39.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:22:0)
[04:51:39.074] Timer tick, asking Buildbucket for the build status
[04:51:39.196] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:39.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:23:0) after 1m0s
[04:52:39.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:23:0)
[04:52:39.392] Timer tick, asking Buildbucket for the build status
[04:52:39.472] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:39.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:24:0) after 1m0s
[04:53:39.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:24:0)
[04:53:39.547] Timer tick, asking Buildbucket for the build status
[04:53:39.599] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:39.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:25:0) after 1m0s
[04:54:39.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:25:0)
[04:54:39.629] Timer tick, asking Buildbucket for the build status
[04:54:39.833] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:39.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:26:0) after 1m0s
[04:55:40.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:26:0)
[04:55:40.029] Timer tick, asking Buildbucket for the build status
[04:55:40.188] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:40.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:27:0) after 1m0s
[04:56:40.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:27:0)
[04:56:40.293] Timer tick, asking Buildbucket for the build status
[04:56:40.421] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:40.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:28:0) after 1m0s
[04:57:40.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:28:0)
[04:57:40.529] Timer tick, asking Buildbucket for the build status
[04:57:40.590] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:40.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:29:0) after 1m0s
[04:58:40.693] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:29:0)
[04:58:40.693] Timer tick, asking Buildbucket for the build status
[04:58:40.790] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:40.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:30:0) after 1m0s
[04:59:40.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:30:0)
[04:59:40.839] Timer tick, asking Buildbucket for the build status
[04:59:41.009] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:41.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:31:0) after 1m0s
[05:00:41.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:31:0)
[05:00:41.052] Timer tick, asking Buildbucket for the build status
[05:00:41.233] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:41.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:32:0) after 1m0s
[05:01:41.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:32:0)
[05:01:41.219] Timer tick, asking Buildbucket for the build status
[05:01:41.528] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:41.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:33:0) after 1m0s
[05:02:41.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:33:0)
[05:02:41.534] Timer tick, asking Buildbucket for the build status
[05:02:41.626] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:41.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:34:0) after 1m0s
[05:03:41.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:34:0)
[05:03:41.664] Timer tick, asking Buildbucket for the build status
[05:03:41.744] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:41.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:35:0) after 1m0s
[05:04:41.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:35:0)
[05:04:41.819] Timer tick, asking Buildbucket for the build status
[05:04:42.030] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:42.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:36:0) after 1m0s
[05:05:42.082] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:36:0)
[05:05:42.082] Timer tick, asking Buildbucket for the build status
[05:05:42.169] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:42.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:37:0) after 1m0s
[05:06:42.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:37:0)
[05:06:42.201] Timer tick, asking Buildbucket for the build status
[05:06:42.297] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:42.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:38:0) after 1m0s
[05:07:42.370] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:38:0)
[05:07:42.370] Timer tick, asking Buildbucket for the build status
[05:07:42.452] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:42.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:39:0) after 1m0s
[05:08:42.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:39:0)
[05:08:42.475] Timer tick, asking Buildbucket for the build status
[05:08:42.634] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:42.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:40:0) after 1m0s
[05:09:42.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:40:0)
[05:09:42.658] Timer tick, asking Buildbucket for the build status
[05:09:42.864] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:42.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:41:0) after 1m0s
[05:10:42.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:41:0)
[05:10:42.911] Timer tick, asking Buildbucket for the build status
[05:10:43.048] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:43.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:42:0) after 1m0s
[05:11:43.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:42:0)
[05:11:43.107] Timer tick, asking Buildbucket for the build status
[05:11:43.486] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:43.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:43:0) after 1m0s
[05:12:43.500] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:43:0)
[05:12:43.500] Timer tick, asking Buildbucket for the build status
[05:12:43.980] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:43.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:44:0) after 1m0s
[05:13:44.054] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:44:0)
[05:13:44.054] Timer tick, asking Buildbucket for the build status
[05:13:44.264] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:44.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:45:0) after 1m0s
[05:14:44.285] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:45:0)
[05:14:44.285] Timer tick, asking Buildbucket for the build status
[05:14:44.343] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:44.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:46:0) after 1m0s
[05:15:44.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:46:0)
[05:15:44.404] Timer tick, asking Buildbucket for the build status
[05:15:44.717] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:44.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:47:0) after 1m0s
[05:16:44.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:47:0)
[05:16:44.785] Timer tick, asking Buildbucket for the build status
[05:16:44.970] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:44.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:48:0) after 1m0s
[05:17:44.992] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:48:0)
[05:17:44.992] Timer tick, asking Buildbucket for the build status
[05:17:45.064] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:45.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:49:0) after 1m0s
[05:18:45.080] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:49:0)
[05:18:45.080] Timer tick, asking Buildbucket for the build status
[05:18:45.172] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:45.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:50:0) after 1m0s
[05:19:45.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:50:0)
[05:19:45.245] Timer tick, asking Buildbucket for the build status
[05:19:45.292] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:45.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:51:0) after 1m0s
[05:20:45.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:51:0)
[05:20:45.383] Timer tick, asking Buildbucket for the build status
[05:20:45.449] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:45.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:52:0) after 1m0s
[05:21:45.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:52:0)
[05:21:45.527] Timer tick, asking Buildbucket for the build status
[05:21:45.657] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:45.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:53:0) after 1m0s
[05:22:45.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:53:0)
[05:22:45.721] Timer tick, asking Buildbucket for the build status
[05:22:45.777] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:45.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:54:0) after 1m0s
[05:23:45.792] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:54:0)
[05:23:45.792] Timer tick, asking Buildbucket for the build status
[05:23:45.873] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:45.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:55:0) after 1m0s
[05:24:45.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:55:0)
[05:24:45.932] Timer tick, asking Buildbucket for the build status
[05:24:45.995] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:45.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:56:0) after 1m0s
[05:25:46.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:56:0)
[05:25:46.005] Timer tick, asking Buildbucket for the build status
[05:25:46.074] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:46.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:57:0) after 1m0s
[05:26:46.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:57:0)
[05:26:46.116] Timer tick, asking Buildbucket for the build status
[05:26:46.300] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:46.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:58:0) after 1m0s
[05:27:46.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:58:0)
[05:27:46.319] Timer tick, asking Buildbucket for the build status
[05:27:46.668] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:46.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:59:0) after 1m0s
[05:28:46.733] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:59:0)
[05:28:46.733] Timer tick, asking Buildbucket for the build status
[05:28:46.939] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:46.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:60:0) after 1m0s
[05:29:46.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:60:0)
[05:29:46.985] Timer tick, asking Buildbucket for the build status
[05:29:47.069] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:47.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:61:0) after 1m0s
[05:30:47.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:61:0)
[05:30:47.053] Timer tick, asking Buildbucket for the build status
[05:30:47.322] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:47.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:62:0) after 1m0s
[05:31:47.338] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:62:0)
[05:31:47.338] Timer tick, asking Buildbucket for the build status
[05:31:47.543] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:47.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:63:0) after 1m0s
[05:32:47.566] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:63:0)
[05:32:47.566] Timer tick, asking Buildbucket for the build status
[05:32:47.647] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:47.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:64:0) after 1m0s
[05:33:47.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:64:0)
[05:33:47.739] Timer tick, asking Buildbucket for the build status
[05:33:47.797] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:47.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:65:0) after 1m0s
[05:34:47.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:65:0)
[05:34:47.822] Timer tick, asking Buildbucket for the build status
[05:34:47.997] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:47.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:66:0) after 1m0s
[05:35:48.044] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:66:0)
[05:35:48.044] Timer tick, asking Buildbucket for the build status
[05:35:48.212] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:48.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:67:0) after 1m0s
[05:36:48.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:67:0)
[05:36:48.204] Timer tick, asking Buildbucket for the build status
[05:36:48.350] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:48.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:68:0) after 1m0s
[05:37:48.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:68:0)
[05:37:48.409] Timer tick, asking Buildbucket for the build status
[05:37:48.543] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:48.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:69:0) after 1m0s
[05:38:48.566] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:69:0)
[05:38:48.566] Timer tick, asking Buildbucket for the build status
[05:38:48.855] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:48.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:70:0) after 1m0s
[05:39:48.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:70:0)
[05:39:48.892] Timer tick, asking Buildbucket for the build status
[05:39:48.970] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:48.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:71:0) after 1m0s
[05:40:49.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:71:0)
[05:40:49.072] Timer tick, asking Buildbucket for the build status
[05:40:49.167] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:49.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:72:0) after 1m0s
[05:41:49.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:72:0)
[05:41:49.287] Timer tick, asking Buildbucket for the build status
[05:41:49.736] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:49.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:73:0) after 1m0s
[05:42:49.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:73:0)
[05:42:49.832] Timer tick, asking Buildbucket for the build status
[05:42:49.922] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:49.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:74:0) after 1m0s
[05:43:50.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:74:0)
[05:43:50.151] Timer tick, asking Buildbucket for the build status
[05:43:50.275] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:50.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:75:0) after 1m0s
[05:44:50.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:75:0)
[05:44:50.371] Timer tick, asking Buildbucket for the build status
[05:44:50.437] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:50.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:76:0) after 1m0s
[05:45:50.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:76:0)
[05:45:50.531] Timer tick, asking Buildbucket for the build status
[05:45:50.624] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:50.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:77:0) after 1m0s
[05:46:50.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:77:0)
[05:46:50.892] Timer tick, asking Buildbucket for the build status
[05:46:51.556] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:51.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:78:0) after 1m0s
[05:47:51.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:78:0)
[05:47:51.601] Timer tick, asking Buildbucket for the build status
[05:47:51.667] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:51.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:79:0) after 1m0s
[05:48:51.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:79:0)
[05:48:51.925] Timer tick, asking Buildbucket for the build status
[05:48:52.008] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:52.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:80:0) after 1m0s
[05:49:52.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:80:0)
[05:49:52.209] Timer tick, asking Buildbucket for the build status
[05:49:52.333] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:52.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:81:0) after 1m0s
[05:50:52.635] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:81:0)
[05:50:52.635] Timer tick, asking Buildbucket for the build status
[05:50:52.764] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:52.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:82:0) after 1m0s
[05:51:53.110] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:82:0)
[05:51:53.110] Timer tick, asking Buildbucket for the build status
[05:51:53.525] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:53.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:83:0) after 1m0s
[05:52:53.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:83:0)
[05:52:53.795] Timer tick, asking Buildbucket for the build status
[05:52:53.873] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:53.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:84:0) after 1m0s
[05:53:54.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:84:0)
[05:53:54.158] Timer tick, asking Buildbucket for the build status
[05:53:54.255] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:54.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:85:0) after 1m0s
[05:54:54.399] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:85:0)
[05:54:54.399] Timer tick, asking Buildbucket for the build status
[05:54:54.476] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:54.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:86:0) after 1m0s
[05:55:54.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:86:0)
[05:55:54.505] Timer tick, asking Buildbucket for the build status
[05:55:54.583] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:54.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:87:0) after 1m0s
[05:56:54.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:87:0)
[05:56:54.689] Timer tick, asking Buildbucket for the build status
[05:56:54.865] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:54.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:88:0) after 1m0s
[05:57:55.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:88:0)
[05:57:55.104] Timer tick, asking Buildbucket for the build status
[05:57:55.191] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:55.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:89:0) after 1m0s
[05:58:55.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:89:0)
[05:58:55.735] Timer tick, asking Buildbucket for the build status
[05:58:55.819] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:55.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:90:0) after 1m0s
[05:59:55.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:90:0)
[05:59:55.877] Timer tick, asking Buildbucket for the build status
[05:59:55.954] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:55.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:91:0) after 1m0s
[06:00:56.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:91:0)
[06:00:56.066] Timer tick, asking Buildbucket for the build status
[06:00:56.154] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:56.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:92:0) after 1m0s
[06:01:56.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:92:0)
[06:01:56.168] Timer tick, asking Buildbucket for the build status
[06:01:56.530] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:56.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:93:0) after 1m0s
[06:02:56.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:93:0)
[06:02:56.574] Timer tick, asking Buildbucket for the build status
[06:02:56.661] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:56.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:94:0) after 1m0s
[06:03:56.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:94:0)
[06:03:56.673] Timer tick, asking Buildbucket for the build status
[06:03:56.940] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:56.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:95:0) after 1m0s
[06:04:57.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:95:0)
[06:04:57.025] Timer tick, asking Buildbucket for the build status
[06:04:57.217] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:57.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:96:0) after 1m0s
[06:05:57.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:96:0)
[06:05:57.217] Timer tick, asking Buildbucket for the build status
[06:05:57.270] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:57.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:97:0) after 1m0s
[06:06:57.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:97:0)
[06:06:57.250] Timer tick, asking Buildbucket for the build status
[06:06:57.363] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:57.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:98:0) after 1m0s
[06:07:57.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:98:0)
[06:07:57.349] Timer tick, asking Buildbucket for the build status
[06:07:57.471] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:57.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:99:0) after 1m0s
[06:08:57.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:99:0)
[06:08:57.499] Timer tick, asking Buildbucket for the build status
[06:08:57.804] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:57.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:100:0) after 1m0s
[06:09:57.806] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:100:0)
[06:09:57.806] Timer tick, asking Buildbucket for the build status
[06:09:57.883] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:57.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:101:0) after 1m0s
[06:10:57.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:101:0)
[06:10:57.919] Timer tick, asking Buildbucket for the build status
[06:10:57.983] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:57.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:102:0) after 1m0s
[06:11:58.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:102:0)
[06:11:58.008] Timer tick, asking Buildbucket for the build status
[06:11:58.122] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:58.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:103:0) after 1m0s
[06:12:58.143] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:103:0)
[06:12:58.143] Timer tick, asking Buildbucket for the build status
[06:12:58.191] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:58.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:104:0) after 1m0s
[06:13:58.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:104:0)
[06:13:58.205] Timer tick, asking Buildbucket for the build status
[06:13:58.290] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:58.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:105:0) after 1m0s
[06:14:58.329] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:105:0)
[06:14:58.329] Timer tick, asking Buildbucket for the build status
[06:14:58.406] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:58.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:106:0) after 1m0s
[06:15:58.459] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:106:0)
[06:15:58.459] Timer tick, asking Buildbucket for the build status
[06:15:58.803] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:58.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:107:0) after 1m0s
[06:16:58.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:107:0)
[06:16:58.850] Timer tick, asking Buildbucket for the build status
[06:16:59.077] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:59.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:108:0) after 1m0s
[06:17:59.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:108:0)
[06:17:59.101] Timer tick, asking Buildbucket for the build status
[06:17:59.158] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:59.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:109:0) after 1m0s
[06:18:59.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:109:0)
[06:18:59.157] Timer tick, asking Buildbucket for the build status
[06:18:59.209] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:59.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:110:0) after 1m0s
[06:19:59.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:110:0)
[06:19:59.195] Timer tick, asking Buildbucket for the build status
[06:19:59.263] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:59.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:111:0) after 1m0s
[06:20:59.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:111:0)
[06:20:59.281] Timer tick, asking Buildbucket for the build status
[06:20:59.371] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:59.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:112:0) after 1m0s
[06:21:59.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:112:0)
[06:21:59.421] Timer tick, asking Buildbucket for the build status
[06:21:59.533] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:59.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:113:0) after 1m0s
[06:22:59.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:113:0)
[06:22:59.560] Timer tick, asking Buildbucket for the build status
[06:22:59.616] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:59.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:114:0) after 1m0s
[06:23:59.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:114:0)
[06:23:59.621] Timer tick, asking Buildbucket for the build status
[06:23:59.685] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:59.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:115:0) after 1m0s
[06:24:59.684] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:115:0)
[06:24:59.684] Timer tick, asking Buildbucket for the build status
[06:25:00.439] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:00.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:116:0) after 1m0s
[06:26:00.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:116:0)
[06:26:00.464] Timer tick, asking Buildbucket for the build status
[06:26:00.571] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:00.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:117:0) after 1m0s
[06:27:00.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:117:0)
[06:27:00.641] Timer tick, asking Buildbucket for the build status
[06:27:00.911] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:00.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:118:0) after 1m0s
[06:28:00.966] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:118:0)
[06:28:00.966] Timer tick, asking Buildbucket for the build status
[06:28:01.025] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:01.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:119:0) after 1m0s
[06:29:01.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:119:0)
[06:29:01.316] Timer tick, asking Buildbucket for the build status
[06:29:03.252] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:03.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:120:0) after 1m0s
[06:30:03.296] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:120:0)
[06:30:03.296] Timer tick, asking Buildbucket for the build status
[06:30:03.428] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:03.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:121:0) after 1m0s
[06:31:03.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:121:0)
[06:31:03.503] Timer tick, asking Buildbucket for the build status
[06:31:03.565] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:03.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:122:0) after 1m0s
[06:32:03.638] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:122:0)
[06:32:03.638] Timer tick, asking Buildbucket for the build status
[06:32:03.782] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:03.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:123:0) after 1m0s
[06:33:03.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:123:0)
[06:33:03.819] Timer tick, asking Buildbucket for the build status
[06:33:03.881] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:03.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:124:0) after 1m0s
[06:34:03.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:124:0)
[06:34:03.868] Timer tick, asking Buildbucket for the build status
[06:34:04.075] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:04.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:125:0) after 1m0s
[06:35:04.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:125:0)
[06:35:04.117] Timer tick, asking Buildbucket for the build status
[06:35:04.228] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:04.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:126:0) after 1m0s
[06:36:04.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:126:0)
[06:36:04.384] Timer tick, asking Buildbucket for the build status
[06:36:04.473] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:04.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:127:0) after 1m0s
[06:37:04.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:127:0)
[06:37:04.734] Timer tick, asking Buildbucket for the build status
[06:37:04.837] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:04.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:128:0) after 1m0s
[06:38:05.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:128:0)
[06:38:05.008] Timer tick, asking Buildbucket for the build status
[06:38:05.125] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:05.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:129:0) after 1m0s
[06:39:05.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:129:0)
[06:39:05.465] Timer tick, asking Buildbucket for the build status
[06:39:05.554] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:05.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:130:0) after 1m0s
[06:40:05.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:130:0)
[06:40:05.612] Timer tick, asking Buildbucket for the build status
[06:40:05.670] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:05.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:131:0) after 1m0s
[06:41:05.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:131:0)
[06:41:05.713] Timer tick, asking Buildbucket for the build status
[06:41:05.758] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:05.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:132:0) after 1m0s
[06:42:05.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:132:0)
[06:42:05.760] Timer tick, asking Buildbucket for the build status
[06:42:05.894] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:05.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:133:0) after 1m0s
[06:43:05.954] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:133:0)
[06:43:05.954] Timer tick, asking Buildbucket for the build status
[06:43:06.024] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:06.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:134:0) after 1m0s
[06:44:06.050] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:134:0)
[06:44:06.050] Timer tick, asking Buildbucket for the build status
[06:44:06.199] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:06.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:135:0) after 1m0s
[06:45:06.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:135:0)
[06:45:06.238] Timer tick, asking Buildbucket for the build status
[06:45:06.346] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:06.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:136:0) after 1m0s
[06:46:06.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:136:0)
[06:46:06.386] Timer tick, asking Buildbucket for the build status
[06:46:06.453] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:06.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:137:0) after 1m0s
[06:47:06.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:137:0)
[06:47:06.552] Timer tick, asking Buildbucket for the build status
[06:47:06.647] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:06.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:138:0) after 1m0s
[06:48:06.752] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:138:0)
[06:48:06.752] Timer tick, asking Buildbucket for the build status
[06:48:06.808] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:06.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:139:0) after 1m0s
[06:49:06.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:139:0)
[06:49:06.848] Timer tick, asking Buildbucket for the build status
[06:49:06.918] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:06.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:140:0) after 1m0s
[06:50:06.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:140:0)
[06:50:06.978] Timer tick, asking Buildbucket for the build status
[06:50:07.062] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:07.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:141:0) after 1m0s
[06:51:07.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:141:0)
[06:51:07.674] Timer tick, asking Buildbucket for the build status
[06:51:07.992] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:07.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:142:0) after 1m0s
[06:52:08.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:142:0)
[06:52:08.027] Timer tick, asking Buildbucket for the build status
[06:52:08.127] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:08.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:143:0) after 1m0s
[06:53:08.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:143:0)
[06:53:08.342] Timer tick, asking Buildbucket for the build status
[06:53:08.441] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:08.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:144:0) after 1m0s
[06:54:08.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:144:0)
[06:54:08.817] Timer tick, asking Buildbucket for the build status
[06:54:08.875] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:08.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:145:0) after 1m0s
[06:55:08.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:145:0)
[06:55:08.981] Timer tick, asking Buildbucket for the build status
[06:55:09.063] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:09.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:146:0) after 1m0s
[06:56:09.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:146:0)
[06:56:09.151] Timer tick, asking Buildbucket for the build status
[06:56:09.248] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:09.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:147:0) after 1m0s
[06:57:09.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:147:0)
[06:57:09.378] Timer tick, asking Buildbucket for the build status
[06:57:09.475] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:09.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:148:0) after 1m0s
[06:58:09.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:148:0)
[06:58:09.587] Timer tick, asking Buildbucket for the build status
[06:58:09.646] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:09.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:149:0) after 1m0s
[06:59:09.681] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:149:0)
[06:59:09.681] Timer tick, asking Buildbucket for the build status
[06:59:09.747] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:09.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:150:0) after 1m0s
[07:00:09.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:150:0)
[07:00:09.898] Timer tick, asking Buildbucket for the build status
[07:00:09.982] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:09.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:151:0) after 1m0s
[07:01:10.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:151:0)
[07:01:10.327] Timer tick, asking Buildbucket for the build status
[07:01:10.423] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:10.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:152:0) after 1m0s
[07:02:11.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:152:0)
[07:02:11.026] Timer tick, asking Buildbucket for the build status
[07:02:11.149] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:11.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:153:0) after 1m0s
[07:03:11.331] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:153:0)
[07:03:11.331] Timer tick, asking Buildbucket for the build status
[07:03:11.930] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:11.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:154:0) after 1m0s
[07:04:11.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:154:0)
[07:04:11.930] Timer tick, asking Buildbucket for the build status
[07:04:11.988] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:11.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:155:0) after 1m0s
[07:05:12.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:155:0)
[07:05:12.019] Timer tick, asking Buildbucket for the build status
[07:05:12.088] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:12.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:156:0) after 1m0s
[07:06:12.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:156:0)
[07:06:12.123] Timer tick, asking Buildbucket for the build status
[07:06:12.445] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:12.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:157:0) after 1m0s
[07:07:12.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:157:0)
[07:07:12.535] Timer tick, asking Buildbucket for the build status
[07:07:12.677] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:12.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:158:0) after 1m0s
[07:08:12.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:158:0)
[07:08:12.758] Timer tick, asking Buildbucket for the build status
[07:08:12.893] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:12.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:159:0) after 1m0s
[07:09:12.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:159:0)
[07:09:12.960] Timer tick, asking Buildbucket for the build status
[07:09:13.018] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:13.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:160:0) after 1m0s
[07:10:13.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:160:0)
[07:10:13.078] Timer tick, asking Buildbucket for the build status
[07:10:13.155] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:13.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:161:0) after 1m0s
[07:11:13.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:161:0)
[07:11:13.528] Timer tick, asking Buildbucket for the build status
[07:11:13.588] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:13.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:162:0) after 1m0s
[07:12:13.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:162:0)
[07:12:13.713] Timer tick, asking Buildbucket for the build status
[07:12:13.840] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:13.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:163:0) after 1m0s
[07:13:14.032] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:163:0)
[07:13:14.032] Timer tick, asking Buildbucket for the build status
[07:13:14.323] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:14.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:164:0) after 1m0s
[07:14:14.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:164:0)
[07:14:14.430] Timer tick, asking Buildbucket for the build status
[07:14:14.634] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:14.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:165:0) after 1m0s
[07:15:14.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:165:0)
[07:15:14.738] Timer tick, asking Buildbucket for the build status
[07:15:14.799] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:14.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:166:0) after 1m0s
[07:16:14.792] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:166:0)
[07:16:14.792] Timer tick, asking Buildbucket for the build status
[07:16:15.107] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:15.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:167:0) after 1m0s
[07:17:15.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:167:0)
[07:17:15.235] Timer tick, asking Buildbucket for the build status
[07:17:15.352] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:15.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:168:0) after 1m0s
[07:18:15.478] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:168:0)
[07:18:15.478] Timer tick, asking Buildbucket for the build status
[07:18:15.551] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:15.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:169:0) after 1m0s
[07:19:15.679] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:169:0)
[07:19:15.679] Timer tick, asking Buildbucket for the build status
[07:19:15.741] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:15.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:170:0) after 1m0s
[07:20:15.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:170:0)
[07:20:15.837] Timer tick, asking Buildbucket for the build status
[07:20:16.312] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:16.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:171:0) after 1m0s
[07:21:16.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:171:0)
[07:21:16.413] Timer tick, asking Buildbucket for the build status
[07:21:16.470] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:16.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:172:0) after 1m0s
[07:22:16.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:172:0)
[07:22:16.544] Timer tick, asking Buildbucket for the build status
[07:22:16.702] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:16.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:173:0) after 1m0s
[07:23:16.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:173:0)
[07:23:16.833] Timer tick, asking Buildbucket for the build status
[07:23:16.904] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:16.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:174:0) after 1m0s
[07:24:16.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:174:0)
[07:24:16.918] Timer tick, asking Buildbucket for the build status
[07:24:16.972] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:16.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:175:0) after 1m0s
[07:25:17.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:175:0)
[07:25:17.052] Timer tick, asking Buildbucket for the build status
[07:25:17.324] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:17.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:176:0) after 1m0s
[07:26:17.424] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:176:0)
[07:26:17.424] Timer tick, asking Buildbucket for the build status
[07:26:17.488] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:17.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:177:0) after 1m0s
[07:27:17.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:177:0)
[07:27:17.513] Timer tick, asking Buildbucket for the build status
[07:27:17.844] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:17.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:178:0) after 1m0s
[07:28:17.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:178:0)
[07:28:17.849] Timer tick, asking Buildbucket for the build status
[07:28:17.923] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:17.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:179:0) after 1m0s
[07:29:18.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:179:0)
[07:29:18.027] Timer tick, asking Buildbucket for the build status
[07:29:18.090] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:18.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:180:0) after 1m0s
[07:30:18.119] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:180:0)
[07:30:18.119] Timer tick, asking Buildbucket for the build status
[07:30:18.177] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:18.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:181:0) after 1m0s
[07:31:18.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:181:0)
[07:31:18.217] Timer tick, asking Buildbucket for the build status
[07:31:18.295] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:18.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:182:0) after 1m0s
[07:32:18.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:182:0)
[07:32:18.555] Timer tick, asking Buildbucket for the build status
[07:32:18.655] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:18.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:183:0) after 1m0s
[07:33:18.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:183:0)
[07:33:18.809] Timer tick, asking Buildbucket for the build status
[07:33:18.867] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:18.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:184:0) after 1m0s
[07:34:19.061] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:184:0)
[07:34:19.061] Timer tick, asking Buildbucket for the build status
[07:34:19.314] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:19.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:185:0) after 1m0s
[07:35:19.433] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:185:0)
[07:35:19.433] Timer tick, asking Buildbucket for the build status
[07:35:19.677] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:19.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:186:0) after 1m0s
[07:36:19.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:186:0)
[07:36:19.730] Timer tick, asking Buildbucket for the build status
[07:36:20.115] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:20.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:187:0) after 1m0s
[07:37:20.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:187:0)
[07:37:20.146] Timer tick, asking Buildbucket for the build status
[07:37:20.320] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:20.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:188:0) after 1m0s
[07:38:20.334] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:188:0)
[07:38:20.334] Timer tick, asking Buildbucket for the build status
[07:38:20.439] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:20.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:189:0) after 1m0s
[07:39:20.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:189:0)
[07:39:20.532] Timer tick, asking Buildbucket for the build status
[07:39:20.881] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:20.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:190:0) after 1m0s
[07:40:21.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:190:0)
[07:40:21.368] Timer tick, asking Buildbucket for the build status
[07:40:21.488] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:21.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:191:0) after 1m0s
[07:41:21.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:191:0)
[07:41:21.754] Timer tick, asking Buildbucket for the build status
[07:41:22.035] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:22.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:192:0) after 1m0s
[07:42:22.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:192:0)
[07:42:22.096] Timer tick, asking Buildbucket for the build status
[07:42:22.609] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:22.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:193:0) after 1m0s
[07:43:22.718] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:193:0)
[07:43:22.718] Timer tick, asking Buildbucket for the build status
[07:43:22.773] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:22.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:194:0) after 1m0s
[07:44:22.840] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:194:0)
[07:44:22.840] Timer tick, asking Buildbucket for the build status
[07:44:22.932] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:22.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:195:0) after 1m0s
[07:45:22.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:195:0)
[07:45:22.999] Timer tick, asking Buildbucket for the build status
[07:45:23.265] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:23.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:196:0) after 1m0s
[07:46:23.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:196:0)
[07:46:23.393] Timer tick, asking Buildbucket for the build status
[07:46:23.593] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:23.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:197:0) after 1m0s
[07:47:23.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:197:0)
[07:47:23.845] Timer tick, asking Buildbucket for the build status
[07:47:23.961] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:23.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:198:0) after 1m0s
[07:48:24.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:198:0)
[07:48:24.068] Timer tick, asking Buildbucket for the build status
[07:48:24.193] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:24.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:199:0) after 1m0s
[07:49:24.381] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:199:0)
[07:49:24.381] Timer tick, asking Buildbucket for the build status
[07:49:24.595] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:24.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:200:0) after 1m0s
[07:50:24.680] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:200:0)
[07:50:24.680] Timer tick, asking Buildbucket for the build status
[07:50:24.738] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:24.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:201:0) after 1m0s
[07:51:24.791] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:201:0)
[07:51:24.791] Timer tick, asking Buildbucket for the build status
[07:51:24.930] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:24.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:202:0) after 1m0s
[07:52:25.004] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:202:0)
[07:52:25.004] Timer tick, asking Buildbucket for the build status
[07:52:25.135] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:25.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:203:0) after 1m0s
[07:53:25.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:203:0)
[07:53:25.218] Timer tick, asking Buildbucket for the build status
[07:53:25.288] Build 8857071416184976480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:25.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022501478199268512:204:0) after 1m0s
[07:53:30.382] Received PubSub notification, asking Buildbucket for the build status
[07:53:30.534] Build 8857071416184976480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:53:30.534] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611647608875039",
"created_by": "project:chromium",
"created_ts": "1611635494016888",
"id": "8857071416184976480",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef43b16d47e35d079cf15b48c8cd764df940fe12\"}}",
"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\": \"ef43b16d47e35d079cf15b48c8cd764df940fe12\", \"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-7-y9nh\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1465, \"got_angle_revision\": \"9bbcd86ae719866273598c988709c7ec3d462e1d\", \"got_dawn_revision\": \"73668344d656e4bad2f6a1dd75c85ffae1e56973\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"ef43b16d47e35d079cf15b48c8cd764df940fe12\", \"got_revision_cp\": \"refs/heads/master@{#847052}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"99e54b1c5caf06a25f76faa4c20c9ab1b31bdb61\", \"got_v8_revision\": \"28f6c291a22caef35a8cf0b58ed0889dc8152903\", \"got_v8_revision_cp\": \"refs/heads/9.0.68@{#1}\", \"got_webrtc_revision\": \"ded6636cf43904448ee926d1f2b4352c8a957ca6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33068}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef43b16d47e35d079cf15b48c8cd764df940fe12\"}, \"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-7-y9nh\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5672-4713de1\"], \"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": "1611635541714692",
"status": "COMPLETED",
"status_changed_ts": "1611647609353168",
"tags": [
"build_address:luci.chromium.ci/win-official/1465",
"builder:win-official",
"buildset:commit/git/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef43b16d47e35d079cf15b48c8cd764df940fe12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022501478199268512",
"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/8857071416184976480/+/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:5155c79e41e83610",
"user_agent:luci-scheduler"
],
"updated_ts": "1611647609393280",
"url": "https://ci.chromium.org/b/8857071416184976480",
"utcnow_ts": "1611647610527907"
}
}
[07:53:30.534] Invocation finished in 3h21m57.823322948s with status SUCCEEDED