[12:23:27.269] New invocation is queued and will start shortly
[12:23:28.375] Starting the invocation (attempt 1)
[12:23:28.420] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:23:28.420] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:23:28.420] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021203430730751488",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"09dce73a680b62bf971dac1a56bd53d8a7ba4e8f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9021203430730751488",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/09dce73a680b62bf971dac1a56bd53d8a7ba4e8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09dce73a680b62bf971dac1a56bd53d8a7ba4e8f",
"gitiles_ref:refs/heads/master"
]
}
[12:23:29.074] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612873408513661",
"id": "8855773368753076528",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"09dce73a680b62bf971dac1a56bd53d8a7ba4e8f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612873408964519",
"tags": [
"build_address:luci.chromium.ci/win-official/1571",
"builder:win-official",
"buildset:commit/git/09dce73a680b62bf971dac1a56bd53d8a7ba4e8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09dce73a680b62bf971dac1a56bd53d8a7ba4e8f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021203430730751488",
"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/8855773368753076528/+/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": "1612873408964720",
"url": "https://ci.chromium.org/b/8855773368753076528",
"utcnow_ts": "1612873409068052"
}
}
[12:23:29.074] Task URL: https://ci.chromium.org/b/8855773368753076528
[12:23:29.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:2:0) after 1m0s
[12:24:29.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:2:0)
[12:24:29.077] Timer tick, asking Buildbucket for the build status
[12:24:29.383] Build 8855773368753076528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:24:29.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:3:0) after 1m0s
[12:25:29.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:3:0)
[12:25:29.430] Timer tick, asking Buildbucket for the build status
[12:25:29.796] Build 8855773368753076528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:25:29.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:4:0) after 1m0s
[12:26:29.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:4:0)
[12:26:29.829] Timer tick, asking Buildbucket for the build status
[12:26:29.899] Build 8855773368753076528: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:26:29.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:5:0) after 1m0s
[12:26:53.813] Received PubSub notification, asking Buildbucket for the build status
[12:26:53.879] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:29.995] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:5:0)
[12:27:29.995] Timer tick, asking Buildbucket for the build status
[12:27:30.058] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:30.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:7:0) after 1m0s
[12:28:30.070] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:7:0)
[12:28:30.070] Timer tick, asking Buildbucket for the build status
[12:28:30.596] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:30.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:8:0) after 1m0s
[12:29:30.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:8:0)
[12:29:30.707] Timer tick, asking Buildbucket for the build status
[12:29:30.877] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:30.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:9:0) after 1m0s
[12:30:30.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:9:0)
[12:30:30.896] Timer tick, asking Buildbucket for the build status
[12:30:30.951] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:30.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:10:0) after 1m0s
[12:31:31.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:10:0)
[12:31:31.117] Timer tick, asking Buildbucket for the build status
[12:31:31.182] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:31.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:11:0) after 1m0s
[12:32:31.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:11:0)
[12:32:31.310] Timer tick, asking Buildbucket for the build status
[12:32:31.379] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:31.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:12:0) after 1m0s
[12:33:31.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:12:0)
[12:33:31.400] Timer tick, asking Buildbucket for the build status
[12:33:31.533] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:31.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:13:0) after 1m0s
[12:34:31.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:13:0)
[12:34:31.554] Timer tick, asking Buildbucket for the build status
[12:34:31.622] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:31.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:14:0) after 1m0s
[12:35:31.680] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:14:0)
[12:35:31.680] Timer tick, asking Buildbucket for the build status
[12:35:31.751] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:31.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:15:0) after 1m0s
[12:36:31.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:15:0)
[12:36:31.779] Timer tick, asking Buildbucket for the build status
[12:36:31.841] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:31.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:16:0) after 1m0s
[12:37:31.897] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:16:0)
[12:37:31.897] Timer tick, asking Buildbucket for the build status
[12:37:31.953] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:31.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:17:0) after 1m0s
[12:38:32.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:17:0)
[12:38:32.013] Timer tick, asking Buildbucket for the build status
[12:38:32.120] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:32.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:18:0) after 1m0s
[12:39:32.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:18:0)
[12:39:32.116] Timer tick, asking Buildbucket for the build status
[12:39:32.218] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:32.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:19:0) after 1m0s
[12:40:32.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:19:0)
[12:40:32.272] Timer tick, asking Buildbucket for the build status
[12:40:32.365] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:32.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:20:0) after 1m0s
[12:41:32.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:20:0)
[12:41:32.389] Timer tick, asking Buildbucket for the build status
[12:41:32.502] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:32.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:21:0) after 1m0s
[12:42:32.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:21:0)
[12:42:32.623] Timer tick, asking Buildbucket for the build status
[12:42:32.729] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:32.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:22:0) after 1m0s
[12:43:32.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:22:0)
[12:43:32.750] Timer tick, asking Buildbucket for the build status
[12:43:32.995] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:32.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:23:0) after 1m0s
[12:44:33.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:23:0)
[12:44:33.041] Timer tick, asking Buildbucket for the build status
[12:44:33.108] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:33.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:24:0) after 1m0s
[12:45:33.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:24:0)
[12:45:33.127] Timer tick, asking Buildbucket for the build status
[12:45:33.409] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:33.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:25:0) after 1m0s
[12:46:33.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:25:0)
[12:46:33.419] Timer tick, asking Buildbucket for the build status
[12:46:33.668] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:33.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:26:0) after 1m0s
[12:47:33.654] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:26:0)
[12:47:33.654] Timer tick, asking Buildbucket for the build status
[12:47:33.914] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:33.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:27:0) after 1m0s
[12:48:33.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:27:0)
[12:48:33.968] Timer tick, asking Buildbucket for the build status
[12:48:34.066] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:34.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:28:0) after 1m0s
[12:49:34.089] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:28:0)
[12:49:34.089] Timer tick, asking Buildbucket for the build status
[12:49:34.151] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:34.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:29:0) after 1m0s
[12:50:34.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:29:0)
[12:50:34.328] Timer tick, asking Buildbucket for the build status
[12:50:34.392] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:34.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:30:0) after 1m0s
[12:51:34.624] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:30:0)
[12:51:34.624] Timer tick, asking Buildbucket for the build status
[12:51:34.689] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:34.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:31:0) after 1m0s
[12:52:34.778] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:31:0)
[12:52:34.778] Timer tick, asking Buildbucket for the build status
[12:52:34.867] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:34.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:32:0) after 1m0s
[12:53:34.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:32:0)
[12:53:34.949] Timer tick, asking Buildbucket for the build status
[12:53:35.433] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:35.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:33:0) after 1m0s
[12:54:35.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:33:0)
[12:54:35.486] Timer tick, asking Buildbucket for the build status
[12:54:35.568] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:35.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:34:0) after 1m0s
[12:55:35.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:34:0)
[12:55:35.615] Timer tick, asking Buildbucket for the build status
[12:55:35.792] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:35.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:35:0) after 1m0s
[12:56:35.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:35:0)
[12:56:35.942] Timer tick, asking Buildbucket for the build status
[12:56:36.309] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:36.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:36:0) after 1m0s
[12:57:36.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:36:0)
[12:57:36.332] Timer tick, asking Buildbucket for the build status
[12:57:36.400] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:36.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:37:0) after 1m0s
[12:58:36.427] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:37:0)
[12:58:36.427] Timer tick, asking Buildbucket for the build status
[12:58:36.543] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:36.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:38:0) after 1m0s
[12:59:36.541] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:38:0)
[12:59:36.541] Timer tick, asking Buildbucket for the build status
[12:59:36.600] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:36.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:39:0) after 1m0s
[13:00:36.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:39:0)
[13:00:36.658] Timer tick, asking Buildbucket for the build status
[13:00:37.006] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:37.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:40:0) after 1m0s
[13:01:37.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:40:0)
[13:01:37.056] Timer tick, asking Buildbucket for the build status
[13:01:37.496] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:37.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:41:0) after 1m0s
[13:02:37.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:41:0)
[13:02:37.569] Timer tick, asking Buildbucket for the build status
[13:02:37.631] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:37.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:42:0) after 1m0s
[13:03:37.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:42:0)
[13:03:37.673] Timer tick, asking Buildbucket for the build status
[13:03:37.975] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:37.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:43:0) after 1m0s
[13:04:38.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:43:0)
[13:04:38.034] Timer tick, asking Buildbucket for the build status
[13:04:38.115] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:38.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:44:0) after 1m0s
[13:05:38.290] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:44:0)
[13:05:38.290] Timer tick, asking Buildbucket for the build status
[13:05:38.366] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:38.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:45:0) after 1m0s
[13:06:38.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:45:0)
[13:06:38.440] Timer tick, asking Buildbucket for the build status
[13:06:38.497] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:38.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:46:0) after 1m0s
[13:07:38.478] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:46:0)
[13:07:38.478] Timer tick, asking Buildbucket for the build status
[13:07:38.817] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:38.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:47:0) after 1m0s
[13:08:38.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:47:0)
[13:08:38.858] Timer tick, asking Buildbucket for the build status
[13:08:38.985] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:38.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:48:0) after 1m0s
[13:09:39.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:48:0)
[13:09:39.029] Timer tick, asking Buildbucket for the build status
[13:09:39.100] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:39.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:49:0) after 1m0s
[13:10:39.148] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:49:0)
[13:10:39.148] Timer tick, asking Buildbucket for the build status
[13:10:39.390] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:39.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:50:0) after 1m0s
[13:11:39.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:50:0)
[13:11:39.425] Timer tick, asking Buildbucket for the build status
[13:11:39.485] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:39.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:51:0) after 1m0s
[13:12:39.487] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:51:0)
[13:12:39.487] Timer tick, asking Buildbucket for the build status
[13:12:39.592] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:39.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:52:0) after 1m0s
[13:13:39.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:52:0)
[13:13:39.621] Timer tick, asking Buildbucket for the build status
[13:13:39.988] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:39.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:53:0) after 1m0s
[13:14:40.024] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:53:0)
[13:14:40.024] Timer tick, asking Buildbucket for the build status
[13:14:40.162] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:40.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:54:0) after 1m0s
[13:15:40.184] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:54:0)
[13:15:40.184] Timer tick, asking Buildbucket for the build status
[13:15:40.244] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:40.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:55:0) after 1m0s
[13:16:40.290] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:55:0)
[13:16:40.290] Timer tick, asking Buildbucket for the build status
[13:16:40.443] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:40.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:56:0) after 1m0s
[13:17:40.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:56:0)
[13:17:40.484] Timer tick, asking Buildbucket for the build status
[13:17:40.718] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:40.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:57:0) after 1m0s
[13:18:40.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:57:0)
[13:18:40.750] Timer tick, asking Buildbucket for the build status
[13:18:41.282] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:41.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:58:0) after 1m0s
[13:19:41.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:58:0)
[13:19:41.271] Timer tick, asking Buildbucket for the build status
[13:19:41.505] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:41.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:59:0) after 1m0s
[13:20:41.504] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:59:0)
[13:20:41.505] Timer tick, asking Buildbucket for the build status
[13:20:41.756] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:41.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:60:0) after 1m0s
[13:21:41.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:60:0)
[13:21:41.822] Timer tick, asking Buildbucket for the build status
[13:21:42.985] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:42.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:61:0) after 1m0s
[13:22:43.060] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:61:0)
[13:22:43.060] Timer tick, asking Buildbucket for the build status
[13:22:43.151] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:43.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:62:0) after 1m0s
[13:23:43.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:62:0)
[13:23:43.163] Timer tick, asking Buildbucket for the build status
[13:23:43.599] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:43.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:63:0) after 1m0s
[13:24:43.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:63:0)
[13:24:43.630] Timer tick, asking Buildbucket for the build status
[13:24:43.690] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:43.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:64:0) after 1m0s
[13:25:43.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:64:0)
[13:25:43.757] Timer tick, asking Buildbucket for the build status
[13:25:43.831] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:43.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:65:0) after 1m0s
[13:26:43.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:65:0)
[13:26:43.932] Timer tick, asking Buildbucket for the build status
[13:26:43.998] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:43.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:66:0) after 1m0s
[13:27:44.087] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:66:0)
[13:27:44.087] Timer tick, asking Buildbucket for the build status
[13:27:44.145] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:44.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:67:0) after 1m0s
[13:28:44.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:67:0)
[13:28:44.146] Timer tick, asking Buildbucket for the build status
[13:28:44.280] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:44.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:68:0) after 1m0s
[13:29:44.308] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:68:0)
[13:29:44.308] Timer tick, asking Buildbucket for the build status
[13:29:44.369] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:44.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:69:0) after 1m0s
[13:30:44.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:69:0)
[13:30:44.391] Timer tick, asking Buildbucket for the build status
[13:30:44.564] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:44.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:70:0) after 1m0s
[13:31:44.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:70:0)
[13:31:44.652] Timer tick, asking Buildbucket for the build status
[13:31:44.748] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:44.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:71:0) after 1m0s
[13:32:44.793] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:71:0)
[13:32:44.793] Timer tick, asking Buildbucket for the build status
[13:32:44.855] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:44.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:72:0) after 1m0s
[13:33:44.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:72:0)
[13:33:44.908] Timer tick, asking Buildbucket for the build status
[13:33:44.992] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:44.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:73:0) after 1m0s
[13:34:45.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:73:0)
[13:34:45.021] Timer tick, asking Buildbucket for the build status
[13:34:45.081] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:45.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:74:0) after 1m0s
[13:35:45.119] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:74:0)
[13:35:45.119] Timer tick, asking Buildbucket for the build status
[13:35:45.294] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:45.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:75:0) after 1m0s
[13:36:45.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:75:0)
[13:36:45.339] Timer tick, asking Buildbucket for the build status
[13:36:45.415] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:45.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:76:0) after 1m0s
[13:37:45.492] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:76:0)
[13:37:45.492] Timer tick, asking Buildbucket for the build status
[13:37:45.565] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:45.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:77:0) after 1m0s
[13:38:45.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:77:0)
[13:38:45.619] Timer tick, asking Buildbucket for the build status
[13:38:45.726] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:45.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:78:0) after 1m0s
[13:39:45.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:78:0)
[13:39:45.784] Timer tick, asking Buildbucket for the build status
[13:39:48.561] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:48.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:79:0) after 1m0s
[13:40:48.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:79:0)
[13:40:48.580] Timer tick, asking Buildbucket for the build status
[13:40:48.643] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:48.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:80:0) after 1m0s
[13:41:48.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:80:0)
[13:41:48.708] Timer tick, asking Buildbucket for the build status
[13:41:49.318] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:49.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:81:0) after 1m0s
[13:42:49.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:81:0)
[13:42:49.352] Timer tick, asking Buildbucket for the build status
[13:42:49.410] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:49.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:82:0) after 1m0s
[13:43:49.443] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:82:0)
[13:43:49.443] Timer tick, asking Buildbucket for the build status
[13:43:50.014] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:50.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:83:0) after 1m0s
[13:44:50.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:83:0)
[13:44:50.009] Timer tick, asking Buildbucket for the build status
[13:44:50.210] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:50.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:84:0) after 1m0s
[13:45:50.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:84:0)
[13:45:50.261] Timer tick, asking Buildbucket for the build status
[13:45:50.725] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:50.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:85:0) after 1m0s
[13:46:50.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:85:0)
[13:46:50.781] Timer tick, asking Buildbucket for the build status
[13:46:50.847] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:50.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:86:0) after 1m0s
[13:47:50.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:86:0)
[13:47:50.883] Timer tick, asking Buildbucket for the build status
[13:47:51.111] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:51.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:87:0) after 1m0s
[13:48:51.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:87:0)
[13:48:51.145] Timer tick, asking Buildbucket for the build status
[13:48:51.253] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:51.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:88:0) after 1m0s
[13:49:51.285] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:88:0)
[13:49:51.285] Timer tick, asking Buildbucket for the build status
[13:49:51.772] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:51.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:89:0) after 1m0s
[13:50:51.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:89:0)
[13:50:51.829] Timer tick, asking Buildbucket for the build status
[13:50:51.893] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:51.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:90:0) after 1m0s
[13:51:51.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:90:0)
[13:51:51.887] Timer tick, asking Buildbucket for the build status
[13:51:52.031] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:52.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:91:0) after 1m0s
[13:52:52.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:91:0)
[13:52:52.029] Timer tick, asking Buildbucket for the build status
[13:52:52.096] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:52.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:92:0) after 1m0s
[13:53:52.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:92:0)
[13:53:52.164] Timer tick, asking Buildbucket for the build status
[13:53:52.524] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:52.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:93:0) after 1m0s
[13:54:52.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:93:0)
[13:54:52.601] Timer tick, asking Buildbucket for the build status
[13:54:52.659] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:52.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:94:0) after 1m0s
[13:55:52.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:94:0)
[13:55:52.736] Timer tick, asking Buildbucket for the build status
[13:55:52.978] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:52.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:95:0) after 1m0s
[13:56:53.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:95:0)
[13:56:53.029] Timer tick, asking Buildbucket for the build status
[13:56:54.184] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:54.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:96:0) after 1m0s
[13:57:54.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:96:0)
[13:57:54.256] Timer tick, asking Buildbucket for the build status
[13:57:54.326] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:54.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:97:0) after 1m0s
[13:58:54.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:97:0)
[13:58:54.401] Timer tick, asking Buildbucket for the build status
[13:58:54.574] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:54.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:98:0) after 1m0s
[13:59:54.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:98:0)
[13:59:54.671] Timer tick, asking Buildbucket for the build status
[13:59:54.731] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:54.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:99:0) after 1m0s
[14:00:54.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:99:0)
[14:00:54.892] Timer tick, asking Buildbucket for the build status
[14:00:54.978] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:54.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:100:0) after 1m0s
[14:01:55.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:100:0)
[14:01:55.101] Timer tick, asking Buildbucket for the build status
[14:01:55.285] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:55.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:101:0) after 1m0s
[14:02:55.341] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:101:0)
[14:02:55.341] Timer tick, asking Buildbucket for the build status
[14:02:56.485] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:56.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:102:0) after 1m0s
[14:03:56.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:102:0)
[14:03:56.503] Timer tick, asking Buildbucket for the build status
[14:03:57.320] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:57.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:103:0) after 1m0s
[14:04:57.322] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:103:0)
[14:04:57.322] Timer tick, asking Buildbucket for the build status
[14:04:57.779] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:57.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:104:0) after 1m0s
[14:05:57.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:104:0)
[14:05:57.942] Timer tick, asking Buildbucket for the build status
[14:05:58.120] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:58.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:105:0) after 1m0s
[14:06:58.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:105:0)
[14:06:58.158] Timer tick, asking Buildbucket for the build status
[14:06:58.245] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:58.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:106:0) after 1m0s
[14:07:58.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:106:0)
[14:07:58.342] Timer tick, asking Buildbucket for the build status
[14:07:58.486] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:58.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:107:0) after 1m0s
[14:08:58.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:107:0)
[14:08:58.596] Timer tick, asking Buildbucket for the build status
[14:08:59.012] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:59.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:108:0) after 1m0s
[14:09:59.051] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:108:0)
[14:09:59.051] Timer tick, asking Buildbucket for the build status
[14:09:59.318] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:59.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:109:0) after 1m0s
[14:10:59.329] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:109:0)
[14:10:59.329] Timer tick, asking Buildbucket for the build status
[14:10:59.693] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:59.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:110:0) after 1m0s
[14:11:59.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:110:0)
[14:11:59.804] Timer tick, asking Buildbucket for the build status
[14:12:00.249] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:00.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:111:0) after 1m0s
[14:13:00.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:111:0)
[14:13:00.274] Timer tick, asking Buildbucket for the build status
[14:13:00.335] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:00.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:112:0) after 1m0s
[14:14:00.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:112:0)
[14:14:00.393] Timer tick, asking Buildbucket for the build status
[14:14:00.785] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:00.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:113:0) after 1m0s
[14:15:00.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:113:0)
[14:15:00.798] Timer tick, asking Buildbucket for the build status
[14:15:01.324] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:01.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:114:0) after 1m0s
[14:16:01.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:114:0)
[14:16:01.345] Timer tick, asking Buildbucket for the build status
[14:16:01.409] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:01.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:115:0) after 1m0s
[14:17:01.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:115:0)
[14:17:01.475] Timer tick, asking Buildbucket for the build status
[14:17:01.568] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:01.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:116:0) after 1m0s
[14:18:01.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:116:0)
[14:18:01.584] Timer tick, asking Buildbucket for the build status
[14:18:01.656] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:01.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:117:0) after 1m0s
[14:19:01.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:117:0)
[14:19:01.701] Timer tick, asking Buildbucket for the build status
[14:19:01.828] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:01.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:118:0) after 1m0s
[14:20:01.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:118:0)
[14:20:01.851] Timer tick, asking Buildbucket for the build status
[14:20:02.048] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:02.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:119:0) after 1m0s
[14:21:02.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:119:0)
[14:21:02.068] Timer tick, asking Buildbucket for the build status
[14:21:02.140] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:02.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:120:0) after 1m0s
[14:22:02.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:120:0)
[14:22:02.195] Timer tick, asking Buildbucket for the build status
[14:22:02.592] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:02.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:121:0) after 1m0s
[14:23:02.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:121:0)
[14:23:02.590] Timer tick, asking Buildbucket for the build status
[14:23:02.718] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:02.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:122:0) after 1m0s
[14:24:02.790] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:122:0)
[14:24:02.790] Timer tick, asking Buildbucket for the build status
[14:24:02.899] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:02.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:123:0) after 1m0s
[14:25:02.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:123:0)
[14:25:02.949] Timer tick, asking Buildbucket for the build status
[14:25:03.311] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:03.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:124:0) after 1m0s
[14:26:03.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:124:0)
[14:26:03.391] Timer tick, asking Buildbucket for the build status
[14:26:03.553] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:03.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:125:0) after 1m0s
[14:27:03.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:125:0)
[14:27:03.553] Timer tick, asking Buildbucket for the build status
[14:27:03.627] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:03.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:126:0) after 1m0s
[14:28:03.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:126:0)
[14:28:03.648] Timer tick, asking Buildbucket for the build status
[14:28:03.831] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:03.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:127:0) after 1m0s
[14:29:03.963] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:127:0)
[14:29:03.963] Timer tick, asking Buildbucket for the build status
[14:29:04.409] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:04.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:128:0) after 1m0s
[14:30:04.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:128:0)
[14:30:04.542] Timer tick, asking Buildbucket for the build status
[14:30:04.723] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:04.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:129:0) after 1m0s
[14:31:04.762] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:129:0)
[14:31:04.762] Timer tick, asking Buildbucket for the build status
[14:31:04.828] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:04.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:130:0) after 1m0s
[14:32:04.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:130:0)
[14:32:04.868] Timer tick, asking Buildbucket for the build status
[14:32:05.218] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:05.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:131:0) after 1m0s
[14:33:05.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:131:0)
[14:33:05.411] Timer tick, asking Buildbucket for the build status
[14:33:05.464] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:05.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:132:0) after 1m0s
[14:34:05.497] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:132:0)
[14:34:05.497] Timer tick, asking Buildbucket for the build status
[14:34:06.736] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:06.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:133:0) after 1m0s
[14:35:06.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:133:0)
[14:35:06.758] Timer tick, asking Buildbucket for the build status
[14:35:06.807] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:06.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:134:0) after 1m0s
[14:36:06.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:134:0)
[14:36:06.839] Timer tick, asking Buildbucket for the build status
[14:36:07.032] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:07.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:135:0) after 1m0s
[14:37:07.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:135:0)
[14:37:07.064] Timer tick, asking Buildbucket for the build status
[14:37:07.124] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:07.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:136:0) after 1m0s
[14:38:07.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:136:0)
[14:38:07.145] Timer tick, asking Buildbucket for the build status
[14:38:07.367] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:07.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:137:0) after 1m0s
[14:39:07.443] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:137:0)
[14:39:07.443] Timer tick, asking Buildbucket for the build status
[14:39:07.806] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:07.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:138:0) after 1m0s
[14:40:07.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:138:0)
[14:40:07.781] Timer tick, asking Buildbucket for the build status
[14:40:08.139] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:08.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:139:0) after 1m0s
[14:41:08.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:139:0)
[14:41:08.254] Timer tick, asking Buildbucket for the build status
[14:41:08.324] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:08.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:140:0) after 1m0s
[14:42:08.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:140:0)
[14:42:08.339] Timer tick, asking Buildbucket for the build status
[14:42:08.432] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:08.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:141:0) after 1m0s
[14:43:08.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:141:0)
[14:43:08.531] Timer tick, asking Buildbucket for the build status
[14:43:08.665] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:08.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:142:0) after 1m0s
[14:44:08.852] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:142:0)
[14:44:08.852] Timer tick, asking Buildbucket for the build status
[14:44:09.058] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:09.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:143:0) after 1m0s
[14:45:09.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:143:0)
[14:45:09.192] Timer tick, asking Buildbucket for the build status
[14:45:10.110] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:10.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:144:0) after 1m0s
[14:46:10.284] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:144:0)
[14:46:10.284] Timer tick, asking Buildbucket for the build status
[14:46:10.381] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:10.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:145:0) after 1m0s
[14:47:10.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:145:0)
[14:47:10.482] Timer tick, asking Buildbucket for the build status
[14:47:10.801] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:10.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:146:0) after 1m0s
[14:48:10.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:146:0)
[14:48:10.819] Timer tick, asking Buildbucket for the build status
[14:48:11.101] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:11.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:147:0) after 1m0s
[14:49:11.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:147:0)
[14:49:11.165] Timer tick, asking Buildbucket for the build status
[14:49:11.416] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:11.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:148:0) after 1m0s
[14:50:11.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:148:0)
[14:50:11.585] Timer tick, asking Buildbucket for the build status
[14:50:11.735] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:11.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:149:0) after 1m0s
[14:51:11.801] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:149:0)
[14:51:11.801] Timer tick, asking Buildbucket for the build status
[14:51:11.910] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:11.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:150:0) after 1m0s
[14:52:11.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:150:0)
[14:52:11.909] Timer tick, asking Buildbucket for the build status
[14:52:12.241] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:12.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:151:0) after 1m0s
[14:53:12.299] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:151:0)
[14:53:12.299] Timer tick, asking Buildbucket for the build status
[14:53:12.770] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:12.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:152:0) after 1m0s
[14:54:12.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:152:0)
[14:54:12.768] Timer tick, asking Buildbucket for the build status
[14:54:12.889] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:12.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:153:0) after 1m0s
[14:55:12.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:153:0)
[14:55:12.982] Timer tick, asking Buildbucket for the build status
[14:55:13.050] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:13.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:154:0) after 1m0s
[14:56:13.048] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:154:0)
[14:56:13.048] Timer tick, asking Buildbucket for the build status
[14:56:13.120] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:13.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:155:0) after 1m0s
[14:57:13.139] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:155:0)
[14:57:13.139] Timer tick, asking Buildbucket for the build status
[14:57:13.219] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:13.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:156:0) after 1m0s
[14:58:13.280] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:156:0)
[14:58:13.280] Timer tick, asking Buildbucket for the build status
[14:58:13.340] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:13.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:157:0) after 1m0s
[14:59:13.355] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:157:0)
[14:59:13.355] Timer tick, asking Buildbucket for the build status
[14:59:13.477] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:13.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:158:0) after 1m0s
[15:00:13.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:158:0)
[15:00:13.527] Timer tick, asking Buildbucket for the build status
[15:00:13.846] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:13.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:159:0) after 1m0s
[15:01:13.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:159:0)
[15:01:13.870] Timer tick, asking Buildbucket for the build status
[15:01:14.025] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:14.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:160:0) after 1m0s
[15:02:14.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:160:0)
[15:02:14.016] Timer tick, asking Buildbucket for the build status
[15:02:14.226] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:14.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:161:0) after 1m0s
[15:03:14.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:161:0)
[15:03:14.260] Timer tick, asking Buildbucket for the build status
[15:03:14.369] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:14.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:162:0) after 1m0s
[15:04:14.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:162:0)
[15:04:14.404] Timer tick, asking Buildbucket for the build status
[15:04:15.474] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:15.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:163:0) after 1m0s
[15:05:15.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:163:0)
[15:05:15.524] Timer tick, asking Buildbucket for the build status
[15:05:15.725] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:15.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:164:0) after 1m0s
[15:06:15.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:164:0)
[15:06:15.760] Timer tick, asking Buildbucket for the build status
[15:06:15.968] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:15.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:165:0) after 1m0s
[15:07:16.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:165:0)
[15:07:16.037] Timer tick, asking Buildbucket for the build status
[15:07:16.096] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:16.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:166:0) after 1m0s
[15:08:16.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:166:0)
[15:08:16.171] Timer tick, asking Buildbucket for the build status
[15:08:16.238] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:16.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:167:0) after 1m0s
[15:09:16.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:167:0)
[15:09:16.364] Timer tick, asking Buildbucket for the build status
[15:09:16.564] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:16.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:168:0) after 1m0s
[15:10:16.570] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:168:0)
[15:10:16.570] Timer tick, asking Buildbucket for the build status
[15:10:16.756] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:16.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:169:0) after 1m0s
[15:11:16.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:169:0)
[15:11:16.836] Timer tick, asking Buildbucket for the build status
[15:11:17.155] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:17.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:170:0) after 1m0s
[15:12:17.246] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:170:0)
[15:12:17.246] Timer tick, asking Buildbucket for the build status
[15:12:17.304] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:17.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:171:0) after 1m0s
[15:13:17.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:171:0)
[15:13:17.417] Timer tick, asking Buildbucket for the build status
[15:13:17.486] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:17.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:172:0) after 1m0s
[15:14:17.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:172:0)
[15:14:17.475] Timer tick, asking Buildbucket for the build status
[15:14:17.713] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:17.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:173:0) after 1m0s
[15:15:17.769] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:173:0)
[15:15:17.769] Timer tick, asking Buildbucket for the build status
[15:15:17.833] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:17.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:174:0) after 1m0s
[15:16:17.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:174:0)
[15:16:17.863] Timer tick, asking Buildbucket for the build status
[15:16:17.939] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:17.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:175:0) after 1m0s
[15:17:17.977] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:175:0)
[15:17:17.977] Timer tick, asking Buildbucket for the build status
[15:17:18.041] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:18.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:176:0) after 1m0s
[15:18:18.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:176:0)
[15:18:18.171] Timer tick, asking Buildbucket for the build status
[15:18:18.257] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:18.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:177:0) after 1m0s
[15:19:18.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:177:0)
[15:19:18.555] Timer tick, asking Buildbucket for the build status
[15:19:18.749] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:18.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:178:0) after 1m0s
[15:20:18.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:178:0)
[15:20:18.754] Timer tick, asking Buildbucket for the build status
[15:20:18.921] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:18.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:179:0) after 1m0s
[15:21:19.589] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:179:0)
[15:21:19.589] Timer tick, asking Buildbucket for the build status
[15:21:19.960] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:19.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:180:0) after 1m0s
[15:22:20.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:180:0)
[15:22:20.016] Timer tick, asking Buildbucket for the build status
[15:22:20.235] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:20.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:181:0) after 1m0s
[15:23:20.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:181:0)
[15:23:20.364] Timer tick, asking Buildbucket for the build status
[15:23:20.563] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:20.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:182:0) after 1m0s
[15:24:20.577] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:182:0)
[15:24:20.577] Timer tick, asking Buildbucket for the build status
[15:24:20.913] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:20.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:183:0) after 1m0s
[15:25:21.044] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:183:0)
[15:25:21.044] Timer tick, asking Buildbucket for the build status
[15:25:21.265] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:21.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:184:0) after 1m0s
[15:26:21.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:184:0)
[15:26:21.345] Timer tick, asking Buildbucket for the build status
[15:26:21.570] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:21.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:185:0) after 1m0s
[15:27:21.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:185:0)
[15:27:21.585] Timer tick, asking Buildbucket for the build status
[15:27:21.778] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:21.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:186:0) after 1m0s
[15:28:21.878] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:186:0)
[15:28:21.878] Timer tick, asking Buildbucket for the build status
[15:28:21.941] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:21.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:187:0) after 1m0s
[15:29:22.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:187:0)
[15:29:22.025] Timer tick, asking Buildbucket for the build status
[15:29:22.141] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:22.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:188:0) after 1m0s
[15:30:22.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:188:0)
[15:30:22.182] Timer tick, asking Buildbucket for the build status
[15:30:22.668] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:22.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:189:0) after 1m0s
[15:31:22.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:189:0)
[15:31:22.798] Timer tick, asking Buildbucket for the build status
[15:31:22.853] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:22.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:190:0) after 1m0s
[15:32:22.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:190:0)
[15:32:22.896] Timer tick, asking Buildbucket for the build status
[15:32:22.967] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:22.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:191:0) after 1m0s
[15:33:23.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:191:0)
[15:33:23.024] Timer tick, asking Buildbucket for the build status
[15:33:23.080] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:23.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:192:0) after 1m0s
[15:34:23.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:192:0)
[15:34:23.123] Timer tick, asking Buildbucket for the build status
[15:34:25.085] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:25.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:193:0) after 1m0s
[15:35:25.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:193:0)
[15:35:25.169] Timer tick, asking Buildbucket for the build status
[15:35:25.281] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:25.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:194:0) after 1m0s
[15:36:25.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:194:0)
[15:36:25.337] Timer tick, asking Buildbucket for the build status
[15:36:25.414] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:25.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:195:0) after 1m0s
[15:37:25.492] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:195:0)
[15:37:25.492] Timer tick, asking Buildbucket for the build status
[15:37:25.551] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:25.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:196:0) after 1m0s
[15:38:25.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:196:0)
[15:38:25.619] Timer tick, asking Buildbucket for the build status
[15:38:25.672] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:25.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:197:0) after 1m0s
[15:39:25.698] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:197:0)
[15:39:25.699] Timer tick, asking Buildbucket for the build status
[15:39:26.057] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:26.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:198:0) after 1m0s
[15:40:26.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:198:0)
[15:40:26.277] Timer tick, asking Buildbucket for the build status
[15:40:26.378] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:26.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:199:0) after 1m0s
[15:41:26.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:199:0)
[15:41:26.524] Timer tick, asking Buildbucket for the build status
[15:41:26.604] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:26.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:200:0) after 1m0s
[15:42:26.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:200:0)
[15:42:26.665] Timer tick, asking Buildbucket for the build status
[15:42:26.747] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:26.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:201:0) after 1m0s
[15:43:26.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:201:0)
[15:43:26.970] Timer tick, asking Buildbucket for the build status
[15:43:27.183] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:27.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:202:0) after 1m0s
[15:44:27.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:202:0)
[15:44:27.286] Timer tick, asking Buildbucket for the build status
[15:44:27.400] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:27.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:203:0) after 1m0s
[15:45:27.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:203:0)
[15:45:27.460] Timer tick, asking Buildbucket for the build status
[15:45:27.773] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:27.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:204:0) after 1m0s
[15:46:27.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:204:0)
[15:46:27.849] Timer tick, asking Buildbucket for the build status
[15:46:33.211] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:33.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:205:0) after 1m0s
[15:47:33.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:205:0)
[15:47:33.245] Timer tick, asking Buildbucket for the build status
[15:47:33.312] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:33.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:206:0) after 1m0s
[15:48:33.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:206:0)
[15:48:33.372] Timer tick, asking Buildbucket for the build status
[15:48:33.460] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:33.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:207:0) after 1m0s
[15:49:33.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:207:0)
[15:49:33.641] Timer tick, asking Buildbucket for the build status
[15:49:33.779] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:33.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:208:0) after 1m0s
[15:50:33.869] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:208:0)
[15:50:33.869] Timer tick, asking Buildbucket for the build status
[15:50:33.937] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:33.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:209:0) after 1m0s
[15:51:33.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:209:0)
[15:51:33.973] Timer tick, asking Buildbucket for the build status
[15:51:34.226] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:34.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:210:0) after 1m0s
[15:52:34.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:210:0)
[15:52:34.245] Timer tick, asking Buildbucket for the build status
[15:52:34.306] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:34.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:211:0) after 1m0s
[15:53:34.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:211:0)
[15:53:34.417] Timer tick, asking Buildbucket for the build status
[15:53:34.483] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:34.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:212:0) after 1m0s
[15:54:34.479] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:212:0)
[15:54:34.479] Timer tick, asking Buildbucket for the build status
[15:54:34.736] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:34.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:213:0) after 1m0s
[15:55:34.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:213:0)
[15:55:34.867] Timer tick, asking Buildbucket for the build status
[15:55:35.072] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:35.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:214:0) after 1m0s
[15:56:35.103] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:214:0)
[15:56:35.103] Timer tick, asking Buildbucket for the build status
[15:56:35.165] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:35.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:215:0) after 1m0s
[15:57:35.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:215:0)
[15:57:35.195] Timer tick, asking Buildbucket for the build status
[15:57:35.321] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:35.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:216:0) after 1m0s
[15:58:35.333] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:216:0)
[15:58:35.333] Timer tick, asking Buildbucket for the build status
[15:58:35.524] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:35.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:217:0) after 1m0s
[15:59:35.549] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:217:0)
[15:59:35.549] Timer tick, asking Buildbucket for the build status
[15:59:36.207] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:36.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:218:0) after 1m0s
[16:00:36.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:218:0)
[16:00:36.260] Timer tick, asking Buildbucket for the build status
[16:00:36.331] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:36.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:219:0) after 1m0s
[16:01:36.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:219:0)
[16:01:36.411] Timer tick, asking Buildbucket for the build status
[16:01:36.489] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:36.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:220:0) after 1m0s
[16:02:36.478] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:220:0)
[16:02:36.478] Timer tick, asking Buildbucket for the build status
[16:02:36.576] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:36.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:221:0) after 1m0s
[16:03:36.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:221:0)
[16:03:36.603] Timer tick, asking Buildbucket for the build status
[16:03:36.781] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:36.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:222:0) after 1m0s
[16:04:36.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:222:0)
[16:04:36.868] Timer tick, asking Buildbucket for the build status
[16:04:37.176] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:37.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:223:0) after 1m0s
[16:05:37.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:223:0)
[16:05:37.157] Timer tick, asking Buildbucket for the build status
[16:05:37.213] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:37.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:224:0) after 1m0s
[16:06:37.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:224:0)
[16:06:37.254] Timer tick, asking Buildbucket for the build status
[16:06:37.438] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:37.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:225:0) after 1m0s
[16:07:37.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:225:0)
[16:07:37.510] Timer tick, asking Buildbucket for the build status
[16:07:37.579] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:37.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:226:0) after 1m0s
[16:08:37.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:226:0)
[16:08:37.697] Timer tick, asking Buildbucket for the build status
[16:08:37.841] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:37.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:227:0) after 1m0s
[16:09:37.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:227:0)
[16:09:37.983] Timer tick, asking Buildbucket for the build status
[16:09:38.144] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:38.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:228:0) after 1m0s
[16:10:38.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:228:0)
[16:10:38.165] Timer tick, asking Buildbucket for the build status
[16:10:38.548] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:38.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:229:0) after 1m0s
[16:11:38.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:229:0)
[16:11:38.632] Timer tick, asking Buildbucket for the build status
[16:11:38.721] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:38.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:230:0) after 1m0s
[16:12:38.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:230:0)
[16:12:38.757] Timer tick, asking Buildbucket for the build status
[16:12:38.847] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:38.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:231:0) after 1m0s
[16:13:38.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:231:0)
[16:13:38.849] Timer tick, asking Buildbucket for the build status
[16:13:39.199] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:39.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:232:0) after 1m0s
[16:14:39.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:232:0)
[16:14:39.298] Timer tick, asking Buildbucket for the build status
[16:14:39.735] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:39.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:233:0) after 1m0s
[16:15:39.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:233:0)
[16:15:39.757] Timer tick, asking Buildbucket for the build status
[16:15:39.821] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:39.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:234:0) after 1m0s
[16:16:39.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:234:0)
[16:16:39.933] Timer tick, asking Buildbucket for the build status
[16:16:40.159] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:40.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:235:0) after 1m0s
[16:17:40.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:235:0)
[16:17:40.193] Timer tick, asking Buildbucket for the build status
[16:17:40.274] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:40.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:236:0) after 1m0s
[16:18:40.359] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:236:0)
[16:18:40.359] Timer tick, asking Buildbucket for the build status
[16:18:40.588] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:40.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:237:0) after 1m0s
[16:19:40.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:237:0)
[16:19:40.639] Timer tick, asking Buildbucket for the build status
[16:19:41.072] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:41.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:238:0) after 1m0s
[16:20:41.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:238:0)
[16:20:41.106] Timer tick, asking Buildbucket for the build status
[16:20:41.296] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:41.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:239:0) after 1m0s
[16:21:41.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:239:0)
[16:21:41.342] Timer tick, asking Buildbucket for the build status
[16:21:41.466] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:41.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:240:0) after 1m0s
[16:22:41.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:240:0)
[16:22:41.471] Timer tick, asking Buildbucket for the build status
[16:22:41.598] Build 8855773368753076528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:41.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021203430730751488:241:0) after 1m0s
[16:22:44.800] Received PubSub notification, asking Buildbucket for the build status
[16:22:45.157] Build 8855773368753076528: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:22:45.157] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612887763736778",
"created_by": "project:chromium",
"created_ts": "1612873408513661",
"failure_reason": "BUILD_FAILURE",
"id": "8855773368753076528",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"09dce73a680b62bf971dac1a56bd53d8a7ba4e8f\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[35542/35667] LINK weblayer_browsertests.exe weblayer_browsertests.exe.pdb\\nFAILED: weblayer_browsertests.exe weblayer_browsertests.exe.pdb\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"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\": \"09dce73a680b62bf971dac1a56bd53d8a7ba4e8f\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-8-ne4u\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1571, \"got_angle_revision\": \"80335a11165f7809a736429f0a5e13bd63e09765\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"09dce73a680b62bf971dac1a56bd53d8a7ba4e8f\", \"got_revision_cp\": \"refs/heads/master@{#852161}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"9d35d544ce966098fdcb36107835d57a4dc1f32d\", \"got_v8_revision\": \"b19b2448ed8ecdc8965621750ac648a2331c0c18\", \"got_v8_revision_cp\": \"refs/heads/9.0.144@{#1}\", \"got_webrtc_revision\": \"54ea85c2cd3a2cb1b8f440a71aa9676a9b88135c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33190}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"09dce73a680b62bf971dac1a56bd53d8a7ba4e8f\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-ne4u\"], \"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\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[35542/35667] LINK weblayer_browsertests.exe weblayer_browsertests.exe.pdb\\nFAILED: weblayer_browsertests.exe weblayer_browsertests.exe.pdb\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612873612809016",
"status": "COMPLETED",
"status_changed_ts": "1612873613528233",
"tags": [
"build_address:luci.chromium.ci/win-official/1571",
"builder:win-official",
"buildset:commit/git/09dce73a680b62bf971dac1a56bd53d8a7ba4e8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/09dce73a680b62bf971dac1a56bd53d8a7ba4e8f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021203430730751488",
"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/8855773368753076528/+/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:519f90ac52bfa610",
"user_agent:luci-scheduler"
],
"updated_ts": "1612887764321318",
"url": "https://ci.chromium.org/b/8855773368753076528",
"utcnow_ts": "1612887765133946"
}
}
[16:22:45.157] Invocation finished in 3h59m17.913150953s with status FAILED