[23:39:10.900] New invocation is queued and will start shortly
[23:39:12.163] Starting the invocation (attempt 1)
[23:39:12.287] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:39:12.287] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:39:12.288] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018443008660337792",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9018443008660337792",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd",
"gitiles_ref:refs/heads/master"
]
}
[23:39:13.914] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615505952409394",
"id": "8853012946404978704",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615505953739372",
"tags": [
"build_address:luci.chromium.ci/win-official/1733",
"builder:win-official",
"buildset:commit/git/ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018443008660337792",
"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/8853012946404978704/+/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": "1615505953739678",
"url": "https://ci.chromium.org/b/8853012946404978704",
"utcnow_ts": "1615505953906607"
}
}
[23:39:13.914] Task URL: https://ci.chromium.org/b/8853012946404978704
[23:39:13.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:2:0) after 1m0s
[23:40:13.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:2:0)
[23:40:13.920] Timer tick, asking Buildbucket for the build status
[23:40:14.010] Build 8853012946404978704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:14.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:3:0) after 1m0s
[23:41:14.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:3:0)
[23:41:14.131] Timer tick, asking Buildbucket for the build status
[23:41:14.191] Build 8853012946404978704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:14.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:4:0) after 1m0s
[23:42:14.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:4:0)
[23:42:14.309] Timer tick, asking Buildbucket for the build status
[23:42:14.370] Build 8853012946404978704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:14.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:5:0) after 1m0s
[23:43:14.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:5:0)
[23:43:14.485] Timer tick, asking Buildbucket for the build status
[23:43:14.571] Build 8853012946404978704: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:14.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:6:0) after 1m0s
[23:44:10.076] Received PubSub notification, asking Buildbucket for the build status
[23:44:10.163] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:14.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:6:0)
[23:44:14.650] Timer tick, asking Buildbucket for the build status
[23:44:15.028] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:15.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:8:0) after 1m0s
[23:45:15.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:8:0)
[23:45:15.043] Timer tick, asking Buildbucket for the build status
[23:45:15.117] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:15.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:9:0) after 1m0s
[23:46:15.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:9:0)
[23:46:15.125] Timer tick, asking Buildbucket for the build status
[23:46:15.273] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:15.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:10:0) after 1m0s
[23:47:15.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:10:0)
[23:47:15.309] Timer tick, asking Buildbucket for the build status
[23:47:15.556] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:15.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:11:0) after 1m0s
[23:48:15.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:11:0)
[23:48:15.663] Timer tick, asking Buildbucket for the build status
[23:48:15.728] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:15.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:12:0) after 1m0s
[23:49:15.897] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:12:0)
[23:49:15.897] Timer tick, asking Buildbucket for the build status
[23:49:15.975] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:15.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:13:0) after 1m0s
[23:50:15.980] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:13:0)
[23:50:15.980] Timer tick, asking Buildbucket for the build status
[23:50:16.042] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:16.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:14:0) after 1m0s
[23:51:16.081] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:14:0)
[23:51:16.081] Timer tick, asking Buildbucket for the build status
[23:51:16.160] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:16.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:15:0) after 1m0s
[23:52:16.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:15:0)
[23:52:16.228] Timer tick, asking Buildbucket for the build status
[23:52:16.454] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:16.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:16:0) after 1m0s
[23:53:16.489] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:16:0)
[23:53:16.489] Timer tick, asking Buildbucket for the build status
[23:53:16.559] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:16.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:17:0) after 1m0s
[23:54:16.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:17:0)
[23:54:16.598] Timer tick, asking Buildbucket for the build status
[23:54:16.762] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:16.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:18:0) after 1m0s
[23:55:16.796] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:18:0)
[23:55:16.796] Timer tick, asking Buildbucket for the build status
[23:55:16.869] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:16.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:19:0) after 1m0s
[23:56:16.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:19:0)
[23:56:16.889] Timer tick, asking Buildbucket for the build status
[23:56:17.039] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:17.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:20:0) after 1m0s
[23:57:17.080] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:20:0)
[23:57:17.080] Timer tick, asking Buildbucket for the build status
[23:57:17.910] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:17.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:21:0) after 1m0s
[23:58:17.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:21:0)
[23:58:17.957] Timer tick, asking Buildbucket for the build status
[23:58:18.013] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:18.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:22:0) after 1m0s
[23:59:18.073] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:22:0)
[23:59:18.073] Timer tick, asking Buildbucket for the build status
[23:59:18.167] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:18.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:23:0) after 1m0s
[00:00:18.210] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:23:0)
[00:00:18.210] Timer tick, asking Buildbucket for the build status
[00:00:18.284] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:18.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:24:0) after 1m0s
[00:01:18.320] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:24:0)
[00:01:18.320] Timer tick, asking Buildbucket for the build status
[00:01:18.395] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:18.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:25:0) after 1m0s
[00:02:18.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:25:0)
[00:02:18.447] Timer tick, asking Buildbucket for the build status
[00:02:18.518] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:18.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:26:0) after 1m0s
[00:03:18.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:26:0)
[00:03:18.587] Timer tick, asking Buildbucket for the build status
[00:03:18.669] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:18.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:27:0) after 1m0s
[00:04:18.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:27:0)
[00:04:18.781] Timer tick, asking Buildbucket for the build status
[00:04:18.856] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:18.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:28:0) after 1m0s
[00:05:18.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:28:0)
[00:05:18.870] Timer tick, asking Buildbucket for the build status
[00:05:18.951] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:18.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:29:0) after 1m0s
[00:06:19.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:29:0)
[00:06:19.064] Timer tick, asking Buildbucket for the build status
[00:06:19.160] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:19.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:30:0) after 1m0s
[00:07:19.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:30:0)
[00:07:19.192] Timer tick, asking Buildbucket for the build status
[00:07:19.265] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:19.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:31:0) after 1m0s
[00:08:19.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:31:0)
[00:08:19.422] Timer tick, asking Buildbucket for the build status
[00:08:19.500] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:19.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:32:0) after 1m0s
[00:09:19.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:32:0)
[00:09:19.516] Timer tick, asking Buildbucket for the build status
[00:09:19.604] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:19.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:33:0) after 1m0s
[00:10:19.703] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:33:0)
[00:10:19.703] Timer tick, asking Buildbucket for the build status
[00:10:19.776] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:19.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:34:0) after 1m0s
[00:11:19.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:34:0)
[00:11:19.784] Timer tick, asking Buildbucket for the build status
[00:11:19.862] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:19.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:35:0) after 1m0s
[00:12:19.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:35:0)
[00:12:19.913] Timer tick, asking Buildbucket for the build status
[00:12:20.001] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:20.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:36:0) after 1m0s
[00:13:19.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:36:0)
[00:13:19.976] Timer tick, asking Buildbucket for the build status
[00:13:20.043] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:20.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:37:0) after 1m0s
[00:14:20.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:37:0)
[00:14:20.074] Timer tick, asking Buildbucket for the build status
[00:14:20.217] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:20.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:38:0) after 1m0s
[00:15:20.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:38:0)
[00:15:20.269] Timer tick, asking Buildbucket for the build status
[00:15:20.350] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:20.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:39:0) after 1m0s
[00:16:20.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:39:0)
[00:16:20.407] Timer tick, asking Buildbucket for the build status
[00:16:20.473] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:20.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:40:0) after 1m0s
[00:17:20.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:40:0)
[00:17:20.534] Timer tick, asking Buildbucket for the build status
[00:17:20.606] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:20.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:41:0) after 1m0s
[00:18:20.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:41:0)
[00:18:20.660] Timer tick, asking Buildbucket for the build status
[00:18:20.733] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:20.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:42:0) after 1m0s
[00:19:20.878] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:42:0)
[00:19:20.878] Timer tick, asking Buildbucket for the build status
[00:19:20.991] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:20.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:43:0) after 1m0s
[00:20:21.040] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:43:0)
[00:20:21.040] Timer tick, asking Buildbucket for the build status
[00:20:21.334] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:21.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:44:0) after 1m0s
[00:21:21.341] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:44:0)
[00:21:21.341] Timer tick, asking Buildbucket for the build status
[00:21:21.417] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:21.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:45:0) after 1m0s
[00:22:21.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:45:0)
[00:22:21.415] Timer tick, asking Buildbucket for the build status
[00:22:21.479] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:21.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:46:0) after 1m0s
[00:23:21.508] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:46:0)
[00:23:21.508] Timer tick, asking Buildbucket for the build status
[00:23:21.586] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:21.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:47:0) after 1m0s
[00:24:21.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:47:0)
[00:24:21.607] Timer tick, asking Buildbucket for the build status
[00:24:21.690] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:21.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:48:0) after 1m0s
[00:25:21.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:48:0)
[00:25:21.686] Timer tick, asking Buildbucket for the build status
[00:25:22.302] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:22.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:49:0) after 1m0s
[00:26:22.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:49:0)
[00:26:22.347] Timer tick, asking Buildbucket for the build status
[00:26:22.495] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:22.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:50:0) after 1m0s
[00:27:22.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:50:0)
[00:27:22.621] Timer tick, asking Buildbucket for the build status
[00:27:22.855] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:22.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:51:0) after 1m0s
[00:28:22.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:51:0)
[00:28:22.870] Timer tick, asking Buildbucket for the build status
[00:28:22.934] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:22.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:52:0) after 1m0s
[00:29:22.980] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:52:0)
[00:29:22.980] Timer tick, asking Buildbucket for the build status
[00:29:23.064] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:23.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:53:0) after 1m0s
[00:30:23.108] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:53:0)
[00:30:23.108] Timer tick, asking Buildbucket for the build status
[00:30:23.182] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:23.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:54:0) after 1m0s
[00:31:23.224] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:54:0)
[00:31:23.224] Timer tick, asking Buildbucket for the build status
[00:31:23.418] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:23.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:55:0) after 1m0s
[00:32:23.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:55:0)
[00:32:23.731] Timer tick, asking Buildbucket for the build status
[00:32:23.844] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:23.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:56:0) after 1m0s
[00:33:23.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:56:0)
[00:33:23.849] Timer tick, asking Buildbucket for the build status
[00:33:23.915] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:23.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:57:0) after 1m0s
[00:34:23.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:57:0)
[00:34:23.983] Timer tick, asking Buildbucket for the build status
[00:34:24.129] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:24.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:58:0) after 1m0s
[00:35:24.179] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:58:0)
[00:35:24.179] Timer tick, asking Buildbucket for the build status
[00:35:24.269] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:24.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:59:0) after 1m0s
[00:36:24.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:59:0)
[00:36:24.293] Timer tick, asking Buildbucket for the build status
[00:36:24.370] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:24.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:60:0) after 1m0s
[00:37:24.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:60:0)
[00:37:24.403] Timer tick, asking Buildbucket for the build status
[00:37:24.475] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:24.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:61:0) after 1m0s
[00:38:24.456] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:61:0)
[00:38:24.456] Timer tick, asking Buildbucket for the build status
[00:38:24.526] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:24.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:62:0) after 1m0s
[00:39:24.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:62:0)
[00:39:24.608] Timer tick, asking Buildbucket for the build status
[00:39:24.693] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:24.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:63:0) after 1m0s
[00:40:24.751] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:63:0)
[00:40:24.751] Timer tick, asking Buildbucket for the build status
[00:40:24.840] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:24.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:64:0) after 1m0s
[00:41:24.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:64:0)
[00:41:24.877] Timer tick, asking Buildbucket for the build status
[00:41:24.978] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:24.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:65:0) after 1m0s
[00:42:25.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:65:0)
[00:42:25.168] Timer tick, asking Buildbucket for the build status
[00:42:25.256] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:25.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:66:0) after 1m0s
[00:43:25.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:66:0)
[00:43:25.292] Timer tick, asking Buildbucket for the build status
[00:43:25.360] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:25.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:67:0) after 1m0s
[00:44:25.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:67:0)
[00:44:25.415] Timer tick, asking Buildbucket for the build status
[00:44:25.616] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:25.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:68:0) after 1m0s
[00:45:25.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:68:0)
[00:45:25.717] Timer tick, asking Buildbucket for the build status
[00:45:25.933] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:25.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:69:0) after 1m0s
[00:46:25.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:69:0)
[00:46:25.932] Timer tick, asking Buildbucket for the build status
[00:46:26.008] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:26.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:70:0) after 1m0s
[00:47:26.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:70:0)
[00:47:26.034] Timer tick, asking Buildbucket for the build status
[00:47:26.113] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:26.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:71:0) after 1m0s
[00:48:26.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:71:0)
[00:48:26.165] Timer tick, asking Buildbucket for the build status
[00:48:26.254] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:26.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:72:0) after 1m0s
[00:49:26.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:72:0)
[00:49:26.303] Timer tick, asking Buildbucket for the build status
[00:49:26.389] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:26.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:73:0) after 1m0s
[00:50:26.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:73:0)
[00:50:26.439] Timer tick, asking Buildbucket for the build status
[00:50:26.557] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:26.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:74:0) after 1m0s
[00:51:26.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:74:0)
[00:51:26.595] Timer tick, asking Buildbucket for the build status
[00:51:28.877] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:28.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:75:0) after 1m0s
[00:52:28.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:75:0)
[00:52:28.892] Timer tick, asking Buildbucket for the build status
[00:52:29.088] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:29.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:76:0) after 1m0s
[00:53:29.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:76:0)
[00:53:29.187] Timer tick, asking Buildbucket for the build status
[00:53:29.624] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:29.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:77:0) after 1m0s
[00:54:29.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:77:0)
[00:54:29.669] Timer tick, asking Buildbucket for the build status
[00:54:29.739] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:29.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:78:0) after 1m0s
[00:55:29.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:78:0)
[00:55:29.779] Timer tick, asking Buildbucket for the build status
[00:55:29.873] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:29.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:79:0) after 1m0s
[00:56:29.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:79:0)
[00:56:29.927] Timer tick, asking Buildbucket for the build status
[00:56:30.160] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:30.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:80:0) after 1m0s
[00:57:30.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:80:0)
[00:57:30.282] Timer tick, asking Buildbucket for the build status
[00:57:30.454] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:30.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:81:0) after 1m0s
[00:58:30.457] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:81:0)
[00:58:30.457] Timer tick, asking Buildbucket for the build status
[00:58:30.651] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:30.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:82:0) after 1m0s
[00:59:30.705] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:82:0)
[00:59:30.705] Timer tick, asking Buildbucket for the build status
[00:59:30.919] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:30.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:83:0) after 1m0s
[01:00:30.964] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:83:0)
[01:00:30.964] Timer tick, asking Buildbucket for the build status
[01:00:31.128] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:31.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:84:0) after 1m0s
[01:01:31.137] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:84:0)
[01:01:31.137] Timer tick, asking Buildbucket for the build status
[01:01:31.200] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:31.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:85:0) after 1m0s
[01:02:31.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:85:0)
[01:02:31.256] Timer tick, asking Buildbucket for the build status
[01:02:31.327] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:31.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:86:0) after 1m0s
[01:03:31.360] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:86:0)
[01:03:31.360] Timer tick, asking Buildbucket for the build status
[01:03:31.439] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:31.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:87:0) after 1m0s
[01:04:31.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:87:0)
[01:04:31.466] Timer tick, asking Buildbucket for the build status
[01:04:31.732] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:31.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:88:0) after 1m0s
[01:05:31.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:88:0)
[01:05:31.765] Timer tick, asking Buildbucket for the build status
[01:05:31.873] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:31.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:89:0) after 1m0s
[01:06:31.953] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:89:0)
[01:06:31.953] Timer tick, asking Buildbucket for the build status
[01:06:32.017] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:32.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:90:0) after 1m0s
[01:07:32.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:90:0)
[01:07:32.043] Timer tick, asking Buildbucket for the build status
[01:07:32.324] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:32.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:91:0) after 1m0s
[01:08:32.390] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:91:0)
[01:08:32.390] Timer tick, asking Buildbucket for the build status
[01:08:32.539] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:32.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:92:0) after 1m0s
[01:09:32.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:92:0)
[01:09:32.666] Timer tick, asking Buildbucket for the build status
[01:09:32.858] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:32.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:93:0) after 1m0s
[01:10:32.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:93:0)
[01:10:32.842] Timer tick, asking Buildbucket for the build status
[01:10:32.914] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:32.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:94:0) after 1m0s
[01:11:32.947] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:94:0)
[01:11:32.947] Timer tick, asking Buildbucket for the build status
[01:11:33.034] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:33.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:95:0) after 1m0s
[01:12:33.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:95:0)
[01:12:33.145] Timer tick, asking Buildbucket for the build status
[01:12:33.444] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:33.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:96:0) after 1m0s
[01:13:33.536] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:96:0)
[01:13:33.536] Timer tick, asking Buildbucket for the build status
[01:13:33.722] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:33.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:97:0) after 1m0s
[01:14:33.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:97:0)
[01:14:33.817] Timer tick, asking Buildbucket for the build status
[01:14:33.979] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:33.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:98:0) after 1m0s
[01:15:34.082] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:98:0)
[01:15:34.082] Timer tick, asking Buildbucket for the build status
[01:15:34.218] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:34.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:99:0) after 1m0s
[01:16:34.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:99:0)
[01:16:34.271] Timer tick, asking Buildbucket for the build status
[01:16:34.447] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:34.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:100:0) after 1m0s
[01:17:34.457] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:100:0)
[01:17:34.457] Timer tick, asking Buildbucket for the build status
[01:17:34.545] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:34.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:101:0) after 1m0s
[01:18:34.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:101:0)
[01:18:34.585] Timer tick, asking Buildbucket for the build status
[01:18:34.677] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:34.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:102:0) after 1m0s
[01:19:34.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:102:0)
[01:19:34.881] Timer tick, asking Buildbucket for the build status
[01:19:34.970] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:34.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:103:0) after 1m0s
[01:20:35.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:103:0)
[01:20:35.229] Timer tick, asking Buildbucket for the build status
[01:20:35.550] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:35.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:104:0) after 1m0s
[01:21:35.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:104:0)
[01:21:35.645] Timer tick, asking Buildbucket for the build status
[01:21:35.842] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:35.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:105:0) after 1m0s
[01:22:35.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:105:0)
[01:22:35.974] Timer tick, asking Buildbucket for the build status
[01:22:36.049] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:36.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:106:0) after 1m0s
[01:23:36.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:106:0)
[01:23:36.123] Timer tick, asking Buildbucket for the build status
[01:23:36.194] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:36.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:107:0) after 1m0s
[01:24:36.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:107:0)
[01:24:36.241] Timer tick, asking Buildbucket for the build status
[01:24:36.353] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:36.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:108:0) after 1m0s
[01:25:36.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:108:0)
[01:25:36.407] Timer tick, asking Buildbucket for the build status
[01:25:36.473] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:36.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:109:0) after 1m0s
[01:26:36.561] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:109:0)
[01:26:36.561] Timer tick, asking Buildbucket for the build status
[01:26:36.639] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:36.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:110:0) after 1m0s
[01:27:36.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:110:0)
[01:27:36.699] Timer tick, asking Buildbucket for the build status
[01:27:36.788] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:36.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:111:0) after 1m0s
[01:28:36.859] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:111:0)
[01:28:36.859] Timer tick, asking Buildbucket for the build status
[01:28:36.927] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:36.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:112:0) after 1m0s
[01:29:36.950] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:112:0)
[01:29:36.950] Timer tick, asking Buildbucket for the build status
[01:29:37.299] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:37.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:113:0) after 1m0s
[01:30:37.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:113:0)
[01:30:37.444] Timer tick, asking Buildbucket for the build status
[01:30:37.555] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:37.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:114:0) after 1m0s
[01:31:37.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:114:0)
[01:31:37.618] Timer tick, asking Buildbucket for the build status
[01:31:37.731] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:37.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:115:0) after 1m0s
[01:32:37.776] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:115:0)
[01:32:37.776] Timer tick, asking Buildbucket for the build status
[01:32:37.845] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:37.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:116:0) after 1m0s
[01:33:37.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:116:0)
[01:33:37.994] Timer tick, asking Buildbucket for the build status
[01:33:38.072] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:38.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:117:0) after 1m0s
[01:34:38.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:117:0)
[01:34:38.169] Timer tick, asking Buildbucket for the build status
[01:34:38.245] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:38.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:118:0) after 1m0s
[01:35:38.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:118:0)
[01:35:38.327] Timer tick, asking Buildbucket for the build status
[01:35:38.406] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:38.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:119:0) after 1m0s
[01:36:38.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:119:0)
[01:36:38.499] Timer tick, asking Buildbucket for the build status
[01:36:38.836] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:38.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:120:0) after 1m0s
[01:37:38.882] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:120:0)
[01:37:38.882] Timer tick, asking Buildbucket for the build status
[01:37:38.956] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:38.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:121:0) after 1m0s
[01:38:39.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:121:0)
[01:38:39.003] Timer tick, asking Buildbucket for the build status
[01:38:39.093] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:39.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:122:0) after 1m0s
[01:39:39.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:122:0)
[01:39:39.132] Timer tick, asking Buildbucket for the build status
[01:39:39.232] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:39.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:123:0) after 1m0s
[01:40:39.324] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:123:0)
[01:40:39.324] Timer tick, asking Buildbucket for the build status
[01:40:39.524] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:39.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:124:0) after 1m0s
[01:41:39.549] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:124:0)
[01:41:39.549] Timer tick, asking Buildbucket for the build status
[01:41:39.612] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:39.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:125:0) after 1m0s
[01:42:39.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:125:0)
[01:42:39.669] Timer tick, asking Buildbucket for the build status
[01:42:39.738] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:39.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:126:0) after 1m0s
[01:43:39.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:126:0)
[01:43:39.837] Timer tick, asking Buildbucket for the build status
[01:43:39.918] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:39.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:127:0) after 1m0s
[01:44:40.010] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:127:0)
[01:44:40.010] Timer tick, asking Buildbucket for the build status
[01:44:40.135] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:40.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:128:0) after 1m0s
[01:45:40.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:128:0)
[01:45:40.168] Timer tick, asking Buildbucket for the build status
[01:45:40.231] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:40.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:129:0) after 1m0s
[01:46:40.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:129:0)
[01:46:40.247] Timer tick, asking Buildbucket for the build status
[01:46:40.318] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:40.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:130:0) after 1m0s
[01:47:40.396] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:130:0)
[01:47:40.396] Timer tick, asking Buildbucket for the build status
[01:47:40.551] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:40.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:131:0) after 1m0s
[01:48:40.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:131:0)
[01:48:40.741] Timer tick, asking Buildbucket for the build status
[01:48:40.817] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:40.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:132:0) after 1m0s
[01:49:41.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:132:0)
[01:49:41.019] Timer tick, asking Buildbucket for the build status
[01:49:41.104] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:41.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:133:0) after 1m0s
[01:50:41.240] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:133:0)
[01:50:41.240] Timer tick, asking Buildbucket for the build status
[01:50:41.306] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:41.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:134:0) after 1m0s
[01:51:41.380] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:134:0)
[01:51:41.380] Timer tick, asking Buildbucket for the build status
[01:51:41.466] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:41.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:135:0) after 1m0s
[01:52:41.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:135:0)
[01:52:41.560] Timer tick, asking Buildbucket for the build status
[01:52:41.727] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:41.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:136:0) after 1m0s
[01:53:41.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:136:0)
[01:53:41.789] Timer tick, asking Buildbucket for the build status
[01:53:41.949] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:41.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:137:0) after 1m0s
[01:54:42.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:137:0)
[01:54:42.013] Timer tick, asking Buildbucket for the build status
[01:54:42.418] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:42.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:138:0) after 1m0s
[01:55:42.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:138:0)
[01:55:42.534] Timer tick, asking Buildbucket for the build status
[01:55:42.591] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:42.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:139:0) after 1m0s
[01:56:42.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:139:0)
[01:56:42.660] Timer tick, asking Buildbucket for the build status
[01:56:43.032] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:43.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:140:0) after 1m0s
[01:57:43.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:140:0)
[01:57:43.113] Timer tick, asking Buildbucket for the build status
[01:57:43.287] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:43.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:141:0) after 1m0s
[01:58:43.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:141:0)
[01:58:43.332] Timer tick, asking Buildbucket for the build status
[01:58:43.400] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:43.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:142:0) after 1m0s
[01:59:43.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:142:0)
[01:59:43.431] Timer tick, asking Buildbucket for the build status
[01:59:43.694] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:43.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:143:0) after 1m0s
[02:00:43.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:143:0)
[02:00:43.726] Timer tick, asking Buildbucket for the build status
[02:00:43.829] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:43.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:144:0) after 1m0s
[02:01:44.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:144:0)
[02:01:44.173] Timer tick, asking Buildbucket for the build status
[02:01:44.255] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:44.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:145:0) after 1m0s
[02:02:44.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:145:0)
[02:02:44.587] Timer tick, asking Buildbucket for the build status
[02:02:44.665] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:44.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:146:0) after 1m0s
[02:03:44.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:146:0)
[02:03:44.900] Timer tick, asking Buildbucket for the build status
[02:03:45.046] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:45.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:147:0) after 1m0s
[02:04:45.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:147:0)
[02:04:45.263] Timer tick, asking Buildbucket for the build status
[02:04:45.642] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:45.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:148:0) after 1m0s
[02:05:45.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:148:0)
[02:05:45.721] Timer tick, asking Buildbucket for the build status
[02:05:45.804] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:45.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:149:0) after 1m0s
[02:06:45.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:149:0)
[02:06:45.976] Timer tick, asking Buildbucket for the build status
[02:06:46.055] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:46.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:150:0) after 1m0s
[02:07:46.319] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:150:0)
[02:07:46.319] Timer tick, asking Buildbucket for the build status
[02:07:46.406] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:46.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:151:0) after 1m0s
[02:08:46.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:151:0)
[02:08:46.470] Timer tick, asking Buildbucket for the build status
[02:08:46.538] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:46.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:152:0) after 1m0s
[02:09:46.617] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:152:0)
[02:09:46.617] Timer tick, asking Buildbucket for the build status
[02:09:46.717] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:46.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:153:0) after 1m0s
[02:10:46.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:153:0)
[02:10:46.774] Timer tick, asking Buildbucket for the build status
[02:10:46.858] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:46.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:154:0) after 1m0s
[02:11:46.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:154:0)
[02:11:46.973] Timer tick, asking Buildbucket for the build status
[02:11:47.041] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:47.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:155:0) after 1m0s
[02:12:47.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:155:0)
[02:12:47.142] Timer tick, asking Buildbucket for the build status
[02:12:47.210] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:47.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:156:0) after 1m0s
[02:13:47.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:156:0)
[02:13:47.350] Timer tick, asking Buildbucket for the build status
[02:13:47.462] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:47.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:157:0) after 1m0s
[02:14:47.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:157:0)
[02:14:47.562] Timer tick, asking Buildbucket for the build status
[02:14:47.644] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:47.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:158:0) after 1m0s
[02:15:47.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:158:0)
[02:15:47.808] Timer tick, asking Buildbucket for the build status
[02:15:47.876] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:47.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:159:0) after 1m0s
[02:16:47.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:159:0)
[02:16:47.976] Timer tick, asking Buildbucket for the build status
[02:16:48.074] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:48.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:160:0) after 1m0s
[02:17:48.156] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:160:0)
[02:17:48.156] Timer tick, asking Buildbucket for the build status
[02:17:48.451] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:48.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:161:0) after 1m0s
[02:18:48.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:161:0)
[02:18:48.533] Timer tick, asking Buildbucket for the build status
[02:18:48.924] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:48.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:162:0) after 1m0s
[02:19:49.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:162:0)
[02:19:49.067] Timer tick, asking Buildbucket for the build status
[02:19:49.166] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:49.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:163:0) after 1m0s
[02:20:49.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:163:0)
[02:20:49.310] Timer tick, asking Buildbucket for the build status
[02:20:49.421] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:49.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:164:0) after 1m0s
[02:21:49.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:164:0)
[02:21:49.525] Timer tick, asking Buildbucket for the build status
[02:21:49.692] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:49.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:165:0) after 1m0s
[02:22:49.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:165:0)
[02:22:49.772] Timer tick, asking Buildbucket for the build status
[02:22:49.845] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:49.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:166:0) after 1m0s
[02:23:49.875] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:166:0)
[02:23:49.875] Timer tick, asking Buildbucket for the build status
[02:23:49.934] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:49.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:167:0) after 1m0s
[02:24:50.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:167:0)
[02:24:50.034] Timer tick, asking Buildbucket for the build status
[02:24:50.147] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:50.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:168:0) after 1m0s
[02:25:50.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:168:0)
[02:25:50.211] Timer tick, asking Buildbucket for the build status
[02:25:50.285] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:50.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:169:0) after 1m0s
[02:26:50.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:169:0)
[02:26:50.515] Timer tick, asking Buildbucket for the build status
[02:26:50.584] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:50.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:170:0) after 1m0s
[02:27:50.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:170:0)
[02:27:50.626] Timer tick, asking Buildbucket for the build status
[02:27:50.896] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:50.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:171:0) after 1m0s
[02:28:50.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:171:0)
[02:28:50.985] Timer tick, asking Buildbucket for the build status
[02:28:51.110] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:51.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:172:0) after 1m0s
[02:29:51.194] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:172:0)
[02:29:51.194] Timer tick, asking Buildbucket for the build status
[02:29:51.512] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:51.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:173:0) after 1m0s
[02:30:51.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:173:0)
[02:30:51.600] Timer tick, asking Buildbucket for the build status
[02:30:51.678] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:51.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:174:0) after 1m0s
[02:31:51.703] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:174:0)
[02:31:51.703] Timer tick, asking Buildbucket for the build status
[02:31:52.007] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:52.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:175:0) after 1m0s
[02:32:52.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:175:0)
[02:32:52.034] Timer tick, asking Buildbucket for the build status
[02:32:52.104] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:52.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:176:0) after 1m0s
[02:33:52.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:176:0)
[02:33:52.135] Timer tick, asking Buildbucket for the build status
[02:33:52.197] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:52.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:177:0) after 1m0s
[02:34:52.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:177:0)
[02:34:52.332] Timer tick, asking Buildbucket for the build status
[02:34:52.415] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:52.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:178:0) after 1m0s
[02:35:52.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:178:0)
[02:35:52.444] Timer tick, asking Buildbucket for the build status
[02:35:52.512] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:52.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:179:0) after 1m0s
[02:36:52.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:179:0)
[02:36:52.531] Timer tick, asking Buildbucket for the build status
[02:36:52.602] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:52.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:180:0) after 1m0s
[02:37:52.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:180:0)
[02:37:52.707] Timer tick, asking Buildbucket for the build status
[02:37:52.846] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:52.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:181:0) after 1m0s
[02:38:52.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:181:0)
[02:38:52.940] Timer tick, asking Buildbucket for the build status
[02:38:53.096] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:53.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:182:0) after 1m0s
[02:39:53.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:182:0)
[02:39:53.233] Timer tick, asking Buildbucket for the build status
[02:39:53.352] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:53.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:183:0) after 1m0s
[02:40:53.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:183:0)
[02:40:53.409] Timer tick, asking Buildbucket for the build status
[02:40:53.563] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:53.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:184:0) after 1m0s
[02:41:53.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:184:0)
[02:41:53.639] Timer tick, asking Buildbucket for the build status
[02:41:53.730] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:53.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:185:0) after 1m0s
[02:42:53.812] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:185:0)
[02:42:53.812] Timer tick, asking Buildbucket for the build status
[02:42:53.937] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:53.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:186:0) after 1m0s
[02:43:53.972] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:186:0)
[02:43:53.972] Timer tick, asking Buildbucket for the build status
[02:43:54.245] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:54.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:187:0) after 1m0s
[02:44:54.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:187:0)
[02:44:54.310] Timer tick, asking Buildbucket for the build status
[02:44:54.401] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:54.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:188:0) after 1m0s
[02:45:54.549] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:188:0)
[02:45:54.549] Timer tick, asking Buildbucket for the build status
[02:45:54.652] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:54.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:189:0) after 1m0s
[02:46:54.680] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:189:0)
[02:46:54.680] Timer tick, asking Buildbucket for the build status
[02:46:55.106] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:55.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:190:0) after 1m0s
[02:47:55.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:190:0)
[02:47:55.135] Timer tick, asking Buildbucket for the build status
[02:47:55.245] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:55.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:191:0) after 1m0s
[02:48:55.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:191:0)
[02:48:55.274] Timer tick, asking Buildbucket for the build status
[02:48:55.339] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:55.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:192:0) after 1m0s
[02:49:55.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:192:0)
[02:49:55.485] Timer tick, asking Buildbucket for the build status
[02:49:55.725] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:55.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:193:0) after 1m0s
[02:50:55.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:193:0)
[02:50:55.851] Timer tick, asking Buildbucket for the build status
[02:50:56.043] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:56.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:194:0) after 1m0s
[02:51:56.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:194:0)
[02:51:56.135] Timer tick, asking Buildbucket for the build status
[02:51:56.199] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:56.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:195:0) after 1m0s
[02:52:56.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:195:0)
[02:52:56.298] Timer tick, asking Buildbucket for the build status
[02:52:56.441] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:56.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:196:0) after 1m0s
[02:53:56.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:196:0)
[02:53:56.514] Timer tick, asking Buildbucket for the build status
[02:53:56.607] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:56.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:197:0) after 1m0s
[02:54:56.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:197:0)
[02:54:56.695] Timer tick, asking Buildbucket for the build status
[02:54:56.788] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:56.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:198:0) after 1m0s
[02:55:56.884] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:198:0)
[02:55:56.884] Timer tick, asking Buildbucket for the build status
[02:55:56.967] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:56.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:199:0) after 1m0s
[02:56:57.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:199:0)
[02:56:57.005] Timer tick, asking Buildbucket for the build status
[02:56:57.233] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:57.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:200:0) after 1m0s
[02:57:57.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:200:0)
[02:57:57.275] Timer tick, asking Buildbucket for the build status
[02:57:57.454] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:57.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:201:0) after 1m0s
[02:58:57.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:201:0)
[02:58:57.510] Timer tick, asking Buildbucket for the build status
[02:58:57.595] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:57.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:202:0) after 1m0s
[02:59:57.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:202:0)
[02:59:57.594] Timer tick, asking Buildbucket for the build status
[02:59:57.669] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:57.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:203:0) after 1m0s
[03:00:57.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:203:0)
[03:00:57.735] Timer tick, asking Buildbucket for the build status
[03:00:57.812] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:57.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:204:0) after 1m0s
[03:01:57.950] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:204:0)
[03:01:57.950] Timer tick, asking Buildbucket for the build status
[03:01:58.030] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:58.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:205:0) after 1m0s
[03:02:58.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:205:0)
[03:02:58.067] Timer tick, asking Buildbucket for the build status
[03:02:58.136] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:58.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:206:0) after 1m0s
[03:03:58.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:206:0)
[03:03:58.171] Timer tick, asking Buildbucket for the build status
[03:03:58.413] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:58.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:207:0) after 1m0s
[03:04:58.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:207:0)
[03:04:58.439] Timer tick, asking Buildbucket for the build status
[03:04:58.520] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:58.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:208:0) after 1m0s
[03:05:58.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:208:0)
[03:05:58.588] Timer tick, asking Buildbucket for the build status
[03:05:58.650] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:58.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:209:0) after 1m0s
[03:07:39.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:209:0)
[03:07:39.383] Timer tick, asking Buildbucket for the build status
[03:07:39.448] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:39.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:210:0) after 1m0s
[03:08:39.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:210:0)
[03:08:39.453] Timer tick, asking Buildbucket for the build status
[03:08:39.560] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:39.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:211:0) after 1m0s
[03:09:39.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:211:0)
[03:09:39.585] Timer tick, asking Buildbucket for the build status
[03:09:39.897] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:39.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:212:0) after 1m0s
[03:10:39.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:212:0)
[03:10:39.940] Timer tick, asking Buildbucket for the build status
[03:10:40.042] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:40.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:213:0) after 1m0s
[03:11:40.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:213:0)
[03:11:40.093] Timer tick, asking Buildbucket for the build status
[03:11:40.194] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:40.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:214:0) after 1m0s
[03:12:40.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:214:0)
[03:12:40.176] Timer tick, asking Buildbucket for the build status
[03:12:40.248] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:40.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:215:0) after 1m0s
[03:13:40.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:215:0)
[03:13:40.313] Timer tick, asking Buildbucket for the build status
[03:13:40.509] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:40.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:216:0) after 1m0s
[03:14:40.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:216:0)
[03:14:40.538] Timer tick, asking Buildbucket for the build status
[03:14:40.817] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:40.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:217:0) after 1m0s
[03:15:40.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:217:0)
[03:15:40.879] Timer tick, asking Buildbucket for the build status
[03:15:40.988] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:40.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:218:0) after 1m0s
[03:16:41.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:218:0)
[03:16:41.043] Timer tick, asking Buildbucket for the build status
[03:16:41.118] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:41.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:219:0) after 1m0s
[03:17:41.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:219:0)
[03:17:41.113] Timer tick, asking Buildbucket for the build status
[03:17:41.261] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:41.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:220:0) after 1m0s
[03:18:41.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:220:0)
[03:18:41.314] Timer tick, asking Buildbucket for the build status
[03:18:41.982] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:41.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:221:0) after 1m0s
[03:19:42.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:221:0)
[03:19:42.012] Timer tick, asking Buildbucket for the build status
[03:19:42.102] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:42.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:222:0) after 1m0s
[03:20:42.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:222:0)
[03:20:42.218] Timer tick, asking Buildbucket for the build status
[03:20:42.308] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:42.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:223:0) after 1m0s
[03:21:42.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:223:0)
[03:21:42.362] Timer tick, asking Buildbucket for the build status
[03:21:42.418] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:42.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:224:0) after 1m0s
[03:22:42.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:224:0)
[03:22:42.491] Timer tick, asking Buildbucket for the build status
[03:22:42.570] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:42.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:225:0) after 1m0s
[03:23:42.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:225:0)
[03:23:42.562] Timer tick, asking Buildbucket for the build status
[03:23:42.624] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:42.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:226:0) after 1m0s
[03:24:42.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:226:0)
[03:24:42.689] Timer tick, asking Buildbucket for the build status
[03:24:42.952] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:42.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:227:0) after 1m0s
[03:25:43.004] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:227:0)
[03:25:43.004] Timer tick, asking Buildbucket for the build status
[03:25:43.239] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:43.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:228:0) after 1m0s
[03:26:43.265] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:228:0)
[03:26:43.265] Timer tick, asking Buildbucket for the build status
[03:26:43.334] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:43.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:229:0) after 1m0s
[03:27:43.363] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:229:0)
[03:27:43.363] Timer tick, asking Buildbucket for the build status
[03:27:43.447] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:43.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:230:0) after 1m0s
[03:28:43.472] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:230:0)
[03:28:43.472] Timer tick, asking Buildbucket for the build status
[03:28:43.623] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:43.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:231:0) after 1m0s
[03:29:43.649] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:231:0)
[03:29:43.649] Timer tick, asking Buildbucket for the build status
[03:29:43.712] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:43.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:232:0) after 1m0s
[03:30:43.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:232:0)
[03:30:43.768] Timer tick, asking Buildbucket for the build status
[03:30:43.828] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:43.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:233:0) after 1m0s
[03:31:43.923] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:233:0)
[03:31:43.923] Timer tick, asking Buildbucket for the build status
[03:31:44.044] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:44.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:234:0) after 1m0s
[03:32:44.129] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:234:0)
[03:32:44.129] Timer tick, asking Buildbucket for the build status
[03:32:44.193] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:44.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:235:0) after 1m0s
[03:33:44.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:235:0)
[03:33:44.178] Timer tick, asking Buildbucket for the build status
[03:33:44.333] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:44.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:236:0) after 1m0s
[03:34:44.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:236:0)
[03:34:44.403] Timer tick, asking Buildbucket for the build status
[03:34:44.485] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:44.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:237:0) after 1m0s
[03:35:44.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:237:0)
[03:35:44.486] Timer tick, asking Buildbucket for the build status
[03:35:44.552] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:44.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:238:0) after 1m0s
[03:36:44.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:238:0)
[03:36:44.619] Timer tick, asking Buildbucket for the build status
[03:36:44.694] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:44.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:239:0) after 1m0s
[03:37:44.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:239:0)
[03:37:44.719] Timer tick, asking Buildbucket for the build status
[03:37:44.952] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:44.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:240:0) after 1m0s
[03:38:44.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:240:0)
[03:38:44.962] Timer tick, asking Buildbucket for the build status
[03:38:45.122] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:45.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:241:0) after 1m0s
[03:39:45.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:241:0)
[03:39:45.173] Timer tick, asking Buildbucket for the build status
[03:39:45.266] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:45.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:242:0) after 1m0s
[03:40:45.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:242:0)
[03:40:45.310] Timer tick, asking Buildbucket for the build status
[03:40:45.380] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:45.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:243:0) after 1m0s
[03:41:45.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:243:0)
[03:41:45.414] Timer tick, asking Buildbucket for the build status
[03:41:45.513] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:45.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:244:0) after 1m0s
[03:42:45.541] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:244:0)
[03:42:45.541] Timer tick, asking Buildbucket for the build status
[03:42:45.798] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:45.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:245:0) after 1m0s
[03:43:45.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:245:0)
[03:43:45.823] Timer tick, asking Buildbucket for the build status
[03:43:45.920] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:45.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:246:0) after 1m0s
[03:44:46.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:246:0)
[03:44:46.006] Timer tick, asking Buildbucket for the build status
[03:44:46.184] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:46.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:247:0) after 1m0s
[03:45:46.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:247:0)
[03:45:46.261] Timer tick, asking Buildbucket for the build status
[03:45:46.540] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:46.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:248:0) after 1m0s
[03:46:46.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:248:0)
[03:46:46.622] Timer tick, asking Buildbucket for the build status
[03:46:46.739] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:46.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:249:0) after 1m0s
[03:47:46.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:249:0)
[03:47:46.746] Timer tick, asking Buildbucket for the build status
[03:47:47.003] Build 8853012946404978704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:47.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9018443008660337792:250:0) after 1m0s
[03:48:46.761] Received PubSub notification, asking Buildbucket for the build status
[03:48:46.858] Build 8853012946404978704: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:48:46.858] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615520926230800",
"created_by": "project:chromium",
"created_ts": "1615505952409394",
"id": "8853012946404978704",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd\"}}",
"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\": \"ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd\", \"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-4-0esy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1733, \"got_angle_revision\": \"fcd7affb6bfd9e17d6c12e836158f8f0fc634b14\", \"got_dawn_revision\": \"9f6bc4e3a95a5ae4e009d76812f752340d1d6181\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd\", \"got_revision_cp\": \"refs/heads/master@{#862190}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"c9b56017cfc3305b810d02aeb9756fbe256cba6f\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"14a626af56675c9d95f9fb207d53366a53a95fbd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33409}\", \"is_cached\": true, \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"builder_cc946d8c9fe3204026095d74248289703a45d938e0b9766be9cb626e466da04f_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-4-0esy\"], \"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\": [\"5754-8c4f760\"], \"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": "1615506249519610",
"status": "COMPLETED",
"status_changed_ts": "1615520926506505",
"tags": [
"build_address:luci.chromium.ci/win-official/1733",
"builder:win-official",
"buildset:commit/git/ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ed5e3edcd10eff428aa3e9bcfece0d6eb0f675dd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018443008660337792",
"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/8853012946404978704/+/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:523c7a1ccbfc3e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615520926514609",
"url": "https://ci.chromium.org/b/8853012946404978704",
"utcnow_ts": "1615520926851794"
}
}
[03:48:46.858] Invocation finished in 4h9m35.982611182s with status SUCCEEDED