[11:32:41.598] New invocation is queued and will start shortly
[11:32:42.736] Starting the invocation (attempt 1)
[11:32:42.784] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:32:42.784] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:32:42.784] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027729605919747120",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"428cca58d5b0e677713cad92b0232a997c47cb75\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9027729605919747120",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/428cca58d5b0e677713cad92b0232a997c47cb75",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/428cca58d5b0e677713cad92b0232a997c47cb75",
"gitiles_ref:refs/heads/master"
]
}
[11:32:43.441] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606649562868815",
"id": "8862299543924892864",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"428cca58d5b0e677713cad92b0232a997c47cb75\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606649563312197",
"tags": [
"build_address:luci.chromium.ci/win32-official/1022",
"builder:win32-official",
"buildset:commit/git/428cca58d5b0e677713cad92b0232a997c47cb75",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/428cca58d5b0e677713cad92b0232a997c47cb75",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027729605919747120",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8862299543924892864/+/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": "1606649563312301",
"url": "https://ci.chromium.org/b/8862299543924892864",
"utcnow_ts": "1606649563433161"
}
}
[11:32:43.441] Task URL: https://ci.chromium.org/b/8862299543924892864
[11:32:43.441] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:2:0) after 1m0s
[11:33:43.814] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:2:0)
[11:33:43.814] Timer tick, asking Buildbucket for the build status
[11:33:43.927] Build 8862299543924892864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:33:43.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:3:0) after 1m0s
[11:34:14.643] Received PubSub notification, asking Buildbucket for the build status
[11:34:14.703] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:44.208] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:3:0)
[11:34:44.208] Timer tick, asking Buildbucket for the build status
[11:34:44.293] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:44.293] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:5:0) after 1m0s
[11:35:44.562] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:5:0)
[11:35:44.562] Timer tick, asking Buildbucket for the build status
[11:35:44.645] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:44.645] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:6:0) after 1m0s
[11:36:44.863] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:6:0)
[11:36:44.863] Timer tick, asking Buildbucket for the build status
[11:36:44.953] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:44.953] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:7:0) after 1m0s
[11:37:45.124] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:7:0)
[11:37:45.124] Timer tick, asking Buildbucket for the build status
[11:37:45.241] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:45.241] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:8:0) after 1m0s
[11:38:45.694] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:8:0)
[11:38:45.694] Timer tick, asking Buildbucket for the build status
[11:38:45.820] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:45.820] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:9:0) after 1m0s
[11:39:46.027] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:9:0)
[11:39:46.027] Timer tick, asking Buildbucket for the build status
[11:39:46.123] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:46.123] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:10:0) after 1m0s
[11:40:46.377] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:10:0)
[11:40:46.377] Timer tick, asking Buildbucket for the build status
[11:40:46.442] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:46.442] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:11:0) after 1m0s
[11:41:46.459] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:11:0)
[11:41:46.459] Timer tick, asking Buildbucket for the build status
[11:41:46.617] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:46.617] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:12:0) after 1m0s
[11:42:46.640] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:12:0)
[11:42:46.640] Timer tick, asking Buildbucket for the build status
[11:42:46.742] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:46.742] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:13:0) after 1m0s
[11:43:46.766] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:13:0)
[11:43:46.766] Timer tick, asking Buildbucket for the build status
[11:43:46.847] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:46.847] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:14:0) after 1m0s
[11:44:46.954] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:14:0)
[11:44:46.954] Timer tick, asking Buildbucket for the build status
[11:44:47.038] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:47.038] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:15:0) after 1m0s
[11:45:47.124] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:15:0)
[11:45:47.124] Timer tick, asking Buildbucket for the build status
[11:45:47.260] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:47.260] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:16:0) after 1m0s
[11:46:47.516] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:16:0)
[11:46:47.516] Timer tick, asking Buildbucket for the build status
[11:46:47.598] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:47.598] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:17:0) after 1m0s
[11:47:47.732] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:17:0)
[11:47:47.732] Timer tick, asking Buildbucket for the build status
[11:47:47.866] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:47.866] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:18:0) after 1m0s
[11:48:47.979] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:18:0)
[11:48:47.979] Timer tick, asking Buildbucket for the build status
[11:48:48.243] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:48.243] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:19:0) after 1m0s
[11:49:48.321] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:19:0)
[11:49:48.321] Timer tick, asking Buildbucket for the build status
[11:49:48.404] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:48.404] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:20:0) after 1m0s
[11:50:48.521] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:20:0)
[11:50:48.521] Timer tick, asking Buildbucket for the build status
[11:50:48.617] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:48.617] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:21:0) after 1m0s
[11:51:50.557] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:21:0)
[11:51:50.557] Timer tick, asking Buildbucket for the build status
[11:51:50.807] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:50.807] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:22:0) after 1m0s
[11:52:50.846] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:22:0)
[11:52:50.846] Timer tick, asking Buildbucket for the build status
[11:52:50.930] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:50.930] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:23:0) after 1m0s
[11:53:50.995] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:23:0)
[11:53:50.995] Timer tick, asking Buildbucket for the build status
[11:53:51.073] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:51.073] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:24:0) after 1m0s
[11:54:51.192] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:24:0)
[11:54:51.192] Timer tick, asking Buildbucket for the build status
[11:54:51.277] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:51.277] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:25:0) after 1m0s
[11:55:51.335] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:25:0)
[11:55:51.335] Timer tick, asking Buildbucket for the build status
[11:55:51.405] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:51.405] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:26:0) after 1m0s
[11:56:51.489] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:26:0)
[11:56:51.489] Timer tick, asking Buildbucket for the build status
[11:56:52.131] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:52.131] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:27:0) after 1m0s
[11:57:52.106] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:27:0)
[11:57:52.106] Timer tick, asking Buildbucket for the build status
[11:57:52.249] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:52.250] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:28:0) after 1m0s
[11:58:52.364] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:28:0)
[11:58:52.364] Timer tick, asking Buildbucket for the build status
[11:58:52.421] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:52.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:29:0) after 1m0s
[11:59:52.476] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:29:0)
[11:59:52.476] Timer tick, asking Buildbucket for the build status
[11:59:52.574] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:52.574] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:30:0) after 1m0s
[12:00:52.654] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:30:0)
[12:00:52.654] Timer tick, asking Buildbucket for the build status
[12:00:52.836] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:52.836] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:31:0) after 1m0s
[12:01:52.931] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:31:0)
[12:01:52.931] Timer tick, asking Buildbucket for the build status
[12:01:53.205] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:53.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:32:0) after 1m0s
[12:02:53.195] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:32:0)
[12:02:53.196] Timer tick, asking Buildbucket for the build status
[12:02:53.523] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:53.523] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:33:0) after 1m0s
[12:03:53.572] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:33:0)
[12:03:53.572] Timer tick, asking Buildbucket for the build status
[12:03:53.642] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:53.642] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:34:0) after 1m0s
[12:04:53.665] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:34:0)
[12:04:53.665] Timer tick, asking Buildbucket for the build status
[12:04:54.025] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:54.025] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:35:0) after 1m0s
[12:05:54.016] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:35:0)
[12:05:54.016] Timer tick, asking Buildbucket for the build status
[12:05:54.159] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:54.159] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:36:0) after 1m0s
[12:06:54.519] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:36:0)
[12:06:54.519] Timer tick, asking Buildbucket for the build status
[12:06:54.588] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:54.588] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:37:0) after 1m0s
[12:07:54.917] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:37:0)
[12:07:54.917] Timer tick, asking Buildbucket for the build status
[12:07:55.021] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:55.021] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:38:0) after 1m0s
[12:08:55.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:38:0)
[12:08:55.095] Timer tick, asking Buildbucket for the build status
[12:08:55.210] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:55.210] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:39:0) after 1m0s
[12:09:55.380] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:39:0)
[12:09:55.380] Timer tick, asking Buildbucket for the build status
[12:09:55.447] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:55.447] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:40:0) after 1m0s
[12:10:55.565] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:40:0)
[12:10:55.565] Timer tick, asking Buildbucket for the build status
[12:10:55.623] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:55.623] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:41:0) after 1m0s
[12:11:55.804] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:41:0)
[12:11:55.804] Timer tick, asking Buildbucket for the build status
[12:11:55.858] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:55.858] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:42:0) after 1m0s
[12:12:55.932] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:42:0)
[12:12:55.932] Timer tick, asking Buildbucket for the build status
[12:12:56.205] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:56.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:43:0) after 1m0s
[12:13:56.415] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:43:0)
[12:13:56.415] Timer tick, asking Buildbucket for the build status
[12:13:56.497] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:56.497] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:44:0) after 1m0s
[12:14:56.533] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:44:0)
[12:14:56.533] Timer tick, asking Buildbucket for the build status
[12:14:56.765] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:56.765] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:45:0) after 1m0s
[12:15:56.845] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:45:0)
[12:15:56.845] Timer tick, asking Buildbucket for the build status
[12:15:56.894] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:56.895] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:46:0) after 1m0s
[12:16:57.182] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:46:0)
[12:16:57.182] Timer tick, asking Buildbucket for the build status
[12:16:57.237] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:57.237] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:47:0) after 1m0s
[12:17:57.390] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:47:0)
[12:17:57.390] Timer tick, asking Buildbucket for the build status
[12:17:57.646] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:57.646] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:48:0) after 1m0s
[12:18:57.735] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:48:0)
[12:18:57.735] Timer tick, asking Buildbucket for the build status
[12:18:57.972] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:57.972] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:49:0) after 1m0s
[12:19:58.098] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:49:0)
[12:19:58.098] Timer tick, asking Buildbucket for the build status
[12:19:58.173] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:58.173] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:50:0) after 1m0s
[12:20:58.298] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:50:0)
[12:20:58.298] Timer tick, asking Buildbucket for the build status
[12:20:58.470] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:58.470] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:51:0) after 1m0s
[12:21:58.585] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:51:0)
[12:21:58.585] Timer tick, asking Buildbucket for the build status
[12:21:58.983] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:58.983] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:52:0) after 1m0s
[12:22:59.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:52:0)
[12:22:59.076] Timer tick, asking Buildbucket for the build status
[12:22:59.555] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:59.555] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:53:0) after 1m0s
[12:23:59.752] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:53:0)
[12:23:59.752] Timer tick, asking Buildbucket for the build status
[12:23:59.976] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:59.976] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:54:0) after 1m0s
[12:25:00.002] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:54:0)
[12:25:00.002] Timer tick, asking Buildbucket for the build status
[12:25:00.239] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:00.239] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:55:0) after 1m0s
[12:26:00.264] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:55:0)
[12:26:00.264] Timer tick, asking Buildbucket for the build status
[12:26:00.640] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:00.640] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:56:0) after 1m0s
[12:27:00.663] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:56:0)
[12:27:00.663] Timer tick, asking Buildbucket for the build status
[12:27:00.777] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:00.777] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:57:0) after 1m0s
[12:28:00.838] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:57:0)
[12:28:00.838] Timer tick, asking Buildbucket for the build status
[12:28:00.939] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:00.939] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:58:0) after 1m0s
[12:29:00.930] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:58:0)
[12:29:00.931] Timer tick, asking Buildbucket for the build status
[12:29:01.023] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:01.023] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:59:0) after 1m0s
[12:30:01.092] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:59:0)
[12:30:01.092] Timer tick, asking Buildbucket for the build status
[12:30:01.199] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:01.199] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:60:0) after 1m0s
[12:31:01.221] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:60:0)
[12:31:01.221] Timer tick, asking Buildbucket for the build status
[12:31:01.515] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:01.515] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:61:0) after 1m0s
[12:32:01.547] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:61:0)
[12:32:01.547] Timer tick, asking Buildbucket for the build status
[12:32:01.840] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:01.840] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:62:0) after 1m0s
[12:33:01.882] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:62:0)
[12:33:01.882] Timer tick, asking Buildbucket for the build status
[12:33:02.031] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:02.031] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:63:0) after 1m0s
[12:34:02.089] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:63:0)
[12:34:02.089] Timer tick, asking Buildbucket for the build status
[12:34:02.258] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:02.258] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:64:0) after 1m0s
[12:35:02.279] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:64:0)
[12:35:02.279] Timer tick, asking Buildbucket for the build status
[12:35:02.734] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:02.734] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:65:0) after 1m0s
[12:36:02.838] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:65:0)
[12:36:02.838] Timer tick, asking Buildbucket for the build status
[12:36:03.302] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:03.302] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:66:0) after 1m0s
[12:37:03.456] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:66:0)
[12:37:03.456] Timer tick, asking Buildbucket for the build status
[12:37:03.564] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:03.564] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:67:0) after 1m0s
[12:38:03.648] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:67:0)
[12:38:03.648] Timer tick, asking Buildbucket for the build status
[12:38:03.896] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:03.896] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:68:0) after 1m0s
[12:39:03.963] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:68:0)
[12:39:03.963] Timer tick, asking Buildbucket for the build status
[12:39:04.065] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:04.066] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:69:0) after 1m0s
[12:40:04.158] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:69:0)
[12:40:04.158] Timer tick, asking Buildbucket for the build status
[12:40:04.224] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:04.224] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:70:0) after 1m0s
[12:41:04.257] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:70:0)
[12:41:04.257] Timer tick, asking Buildbucket for the build status
[12:41:04.334] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:04.334] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:71:0) after 1m0s
[12:42:04.396] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:71:0)
[12:42:04.396] Timer tick, asking Buildbucket for the build status
[12:42:04.606] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:04.606] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:72:0) after 1m0s
[12:43:04.664] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:72:0)
[12:43:04.664] Timer tick, asking Buildbucket for the build status
[12:43:04.794] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:04.794] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:73:0) after 1m0s
[12:44:04.820] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:73:0)
[12:44:04.820] Timer tick, asking Buildbucket for the build status
[12:44:04.982] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:04.982] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:74:0) after 1m0s
[12:45:05.019] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:74:0)
[12:45:05.019] Timer tick, asking Buildbucket for the build status
[12:45:05.115] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:05.115] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:75:0) after 1m0s
[12:46:05.096] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:75:0)
[12:46:05.096] Timer tick, asking Buildbucket for the build status
[12:46:05.155] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:05.155] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:76:0) after 1m0s
[12:47:05.188] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:76:0)
[12:47:05.188] Timer tick, asking Buildbucket for the build status
[12:47:05.258] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:05.258] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:77:0) after 1m0s
[12:48:05.521] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:77:0)
[12:48:05.521] Timer tick, asking Buildbucket for the build status
[12:48:05.590] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:05.590] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:78:0) after 1m0s
[12:49:05.663] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:78:0)
[12:49:05.663] Timer tick, asking Buildbucket for the build status
[12:49:05.782] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:05.782] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:79:0) after 1m0s
[12:50:05.895] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:79:0)
[12:50:05.896] Timer tick, asking Buildbucket for the build status
[12:50:05.954] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:05.954] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:80:0) after 1m0s
[12:51:05.959] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:80:0)
[12:51:05.959] Timer tick, asking Buildbucket for the build status
[12:51:06.156] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:06.156] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:81:0) after 1m0s
[12:52:06.228] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:81:0)
[12:52:06.228] Timer tick, asking Buildbucket for the build status
[12:52:06.499] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:06.499] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:82:0) after 1m0s
[12:53:06.603] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:82:0)
[12:53:06.603] Timer tick, asking Buildbucket for the build status
[12:53:06.695] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:06.695] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:83:0) after 1m0s
[12:54:06.719] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:83:0)
[12:54:06.719] Timer tick, asking Buildbucket for the build status
[12:54:07.136] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:07.136] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:84:0) after 1m0s
[12:55:07.165] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:84:0)
[12:55:07.165] Timer tick, asking Buildbucket for the build status
[12:55:07.419] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:07.419] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:85:0) after 1m0s
[12:56:07.403] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:85:0)
[12:56:07.403] Timer tick, asking Buildbucket for the build status
[12:56:07.484] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:07.484] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:86:0) after 1m0s
[12:57:07.576] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:86:0)
[12:57:07.576] Timer tick, asking Buildbucket for the build status
[12:57:07.700] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:07.700] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:87:0) after 1m0s
[12:58:07.784] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:87:0)
[12:58:07.784] Timer tick, asking Buildbucket for the build status
[12:58:07.843] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:07.843] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:88:0) after 1m0s
[12:59:07.881] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:88:0)
[12:59:07.881] Timer tick, asking Buildbucket for the build status
[12:59:08.194] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:08.194] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:89:0) after 1m0s
[13:00:08.253] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:89:0)
[13:00:08.253] Timer tick, asking Buildbucket for the build status
[13:00:09.486] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:09.486] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:90:0) after 1m0s
[13:01:09.506] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:90:0)
[13:01:09.506] Timer tick, asking Buildbucket for the build status
[13:01:09.687] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:09.687] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:91:0) after 1m0s
[13:02:09.741] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:91:0)
[13:02:09.741] Timer tick, asking Buildbucket for the build status
[13:02:09.823] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:09.823] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:92:0) after 1m0s
[13:03:09.898] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:92:0)
[13:03:09.898] Timer tick, asking Buildbucket for the build status
[13:03:10.057] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:10.057] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:93:0) after 1m0s
[13:04:10.099] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:93:0)
[13:04:10.099] Timer tick, asking Buildbucket for the build status
[13:04:10.264] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:10.264] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:94:0) after 1m0s
[13:05:10.285] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:94:0)
[13:05:10.285] Timer tick, asking Buildbucket for the build status
[13:05:10.665] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:10.665] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:95:0) after 1m0s
[13:06:10.742] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:95:0)
[13:06:10.742] Timer tick, asking Buildbucket for the build status
[13:06:10.814] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:10.814] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:96:0) after 1m0s
[13:07:10.850] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:96:0)
[13:07:10.851] Timer tick, asking Buildbucket for the build status
[13:07:11.123] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:11.123] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:97:0) after 1m0s
[13:08:11.146] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:97:0)
[13:08:11.146] Timer tick, asking Buildbucket for the build status
[13:08:11.289] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:11.289] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:98:0) after 1m0s
[13:09:11.337] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:98:0)
[13:09:11.337] Timer tick, asking Buildbucket for the build status
[13:09:11.869] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:11.869] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:99:0) after 1m0s
[13:10:11.891] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:99:0)
[13:10:11.891] Timer tick, asking Buildbucket for the build status
[13:10:11.955] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:11.955] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:100:0) after 1m0s
[13:11:11.976] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:100:0)
[13:11:11.976] Timer tick, asking Buildbucket for the build status
[13:11:12.171] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:12.171] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:101:0) after 1m0s
[13:12:12.224] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:101:0)
[13:12:12.224] Timer tick, asking Buildbucket for the build status
[13:12:12.536] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:12.536] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:102:0) after 1m0s
[13:13:12.797] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:102:0)
[13:13:12.797] Timer tick, asking Buildbucket for the build status
[13:13:13.115] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:13.115] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:103:0) after 1m0s
[13:14:13.257] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:103:0)
[13:14:13.257] Timer tick, asking Buildbucket for the build status
[13:14:13.380] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:13.380] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:104:0) after 1m0s
[13:15:13.697] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:104:0)
[13:15:13.697] Timer tick, asking Buildbucket for the build status
[13:15:13.945] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:13.945] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:105:0) after 1m0s
[13:16:14.004] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:105:0)
[13:16:14.004] Timer tick, asking Buildbucket for the build status
[13:16:14.203] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:14.203] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:106:0) after 1m0s
[13:17:14.287] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:106:0)
[13:17:14.287] Timer tick, asking Buildbucket for the build status
[13:17:14.345] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:14.345] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:107:0) after 1m0s
[13:18:14.531] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:107:0)
[13:18:14.531] Timer tick, asking Buildbucket for the build status
[13:18:14.709] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:14.709] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:108:0) after 1m0s
[13:19:14.767] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:108:0)
[13:19:14.768] Timer tick, asking Buildbucket for the build status
[13:19:14.971] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:14.971] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:109:0) after 1m0s
[13:20:19.627] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:109:0)
[13:20:19.627] Timer tick, asking Buildbucket for the build status
[13:20:19.693] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:19.693] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:110:0) after 1m0s
[13:21:20.444] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:110:0)
[13:21:20.444] Timer tick, asking Buildbucket for the build status
[13:21:20.667] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:20.667] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:111:0) after 1m0s
[13:22:20.791] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:111:0)
[13:22:20.791] Timer tick, asking Buildbucket for the build status
[13:22:20.922] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:20.922] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:112:0) after 1m0s
[13:23:21.191] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:112:0)
[13:23:21.191] Timer tick, asking Buildbucket for the build status
[13:23:21.253] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:21.253] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:113:0) after 1m0s
[13:24:21.298] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:113:0)
[13:24:21.298] Timer tick, asking Buildbucket for the build status
[13:24:21.536] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:21.536] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:114:0) after 1m0s
[13:25:21.664] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:114:0)
[13:25:21.664] Timer tick, asking Buildbucket for the build status
[13:25:21.842] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:21.842] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:115:0) after 1m0s
[13:26:21.918] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:115:0)
[13:26:21.918] Timer tick, asking Buildbucket for the build status
[13:26:22.279] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:22.279] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:116:0) after 1m0s
[13:27:22.439] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:116:0)
[13:27:22.439] Timer tick, asking Buildbucket for the build status
[13:27:22.689] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:22.689] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:117:0) after 1m0s
[13:28:22.772] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:117:0)
[13:28:22.772] Timer tick, asking Buildbucket for the build status
[13:28:22.955] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:22.955] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:118:0) after 1m0s
[13:29:23.222] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:118:0)
[13:29:23.222] Timer tick, asking Buildbucket for the build status
[13:29:23.798] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:23.798] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:119:0) after 1m0s
[13:30:23.922] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:119:0)
[13:30:23.922] Timer tick, asking Buildbucket for the build status
[13:30:24.062] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:24.062] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:120:0) after 1m0s
[13:31:24.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:120:0)
[13:31:24.076] Timer tick, asking Buildbucket for the build status
[13:31:24.847] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:24.847] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:121:0) after 1m0s
[13:32:24.873] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:121:0)
[13:32:24.873] Timer tick, asking Buildbucket for the build status
[13:32:24.954] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:24.954] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:122:0) after 1m0s
[13:33:24.975] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:122:0)
[13:33:24.975] Timer tick, asking Buildbucket for the build status
[13:33:25.046] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:25.047] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:123:0) after 1m0s
[13:34:25.126] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:123:0)
[13:34:25.126] Timer tick, asking Buildbucket for the build status
[13:34:25.234] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:25.234] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:124:0) after 1m0s
[13:35:25.389] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:124:0)
[13:35:25.389] Timer tick, asking Buildbucket for the build status
[13:35:25.458] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:25.458] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:125:0) after 1m0s
[13:36:25.533] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:125:0)
[13:36:25.533] Timer tick, asking Buildbucket for the build status
[13:36:25.631] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:25.631] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:126:0) after 1m0s
[13:37:25.808] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:126:0)
[13:37:25.808] Timer tick, asking Buildbucket for the build status
[13:37:25.894] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:25.894] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:127:0) after 1m0s
[13:38:25.907] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:127:0)
[13:38:25.907] Timer tick, asking Buildbucket for the build status
[13:38:26.171] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:26.171] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:128:0) after 1m0s
[13:39:26.195] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:128:0)
[13:39:26.195] Timer tick, asking Buildbucket for the build status
[13:39:26.322] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:26.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:129:0) after 1m0s
[13:40:26.345] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:129:0)
[13:40:26.345] Timer tick, asking Buildbucket for the build status
[13:40:26.426] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:26.426] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:130:0) after 1m0s
[13:41:26.584] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:130:0)
[13:41:26.584] Timer tick, asking Buildbucket for the build status
[13:41:26.671] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:26.671] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:131:0) after 1m0s
[13:42:26.773] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:131:0)
[13:42:26.773] Timer tick, asking Buildbucket for the build status
[13:42:26.831] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:26.831] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:132:0) after 1m0s
[13:43:26.918] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:132:0)
[13:43:26.918] Timer tick, asking Buildbucket for the build status
[13:43:27.009] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:27.009] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:133:0) after 1m0s
[13:44:27.034] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:133:0)
[13:44:27.034] Timer tick, asking Buildbucket for the build status
[13:44:27.312] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:27.312] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:134:0) after 1m0s
[13:45:27.434] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:134:0)
[13:45:27.434] Timer tick, asking Buildbucket for the build status
[13:45:27.520] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:27.520] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:135:0) after 1m0s
[13:46:27.581] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:135:0)
[13:46:27.581] Timer tick, asking Buildbucket for the build status
[13:46:27.645] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:27.645] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:136:0) after 1m0s
[13:47:27.694] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:136:0)
[13:47:27.694] Timer tick, asking Buildbucket for the build status
[13:47:27.757] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:27.757] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:137:0) after 1m0s
[13:48:27.738] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:137:0)
[13:48:27.738] Timer tick, asking Buildbucket for the build status
[13:48:28.935] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:28.935] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:138:0) after 1m0s
[13:49:29.006] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:138:0)
[13:49:29.006] Timer tick, asking Buildbucket for the build status
[13:49:29.692] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:29.692] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:139:0) after 1m0s
[13:50:29.738] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:139:0)
[13:50:29.738] Timer tick, asking Buildbucket for the build status
[13:50:29.815] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:29.815] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:140:0) after 1m0s
[13:51:29.869] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:140:0)
[13:51:29.869] Timer tick, asking Buildbucket for the build status
[13:51:29.952] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:29.952] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:141:0) after 1m0s
[13:52:30.058] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:141:0)
[13:52:30.058] Timer tick, asking Buildbucket for the build status
[13:52:30.369] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:30.369] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:142:0) after 1m0s
[13:53:30.392] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:142:0)
[13:53:30.392] Timer tick, asking Buildbucket for the build status
[13:53:30.620] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:30.620] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:143:0) after 1m0s
[13:54:30.697] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:143:0)
[13:54:30.698] Timer tick, asking Buildbucket for the build status
[13:54:30.913] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:30.913] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:144:0) after 1m0s
[13:55:30.992] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:144:0)
[13:55:30.992] Timer tick, asking Buildbucket for the build status
[13:55:31.075] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:31.075] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:145:0) after 1m0s
[13:56:31.223] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:145:0)
[13:56:31.223] Timer tick, asking Buildbucket for the build status
[13:56:31.335] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:31.335] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:146:0) after 1m0s
[13:57:31.427] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:146:0)
[13:57:31.427] Timer tick, asking Buildbucket for the build status
[13:57:31.610] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:31.610] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:147:0) after 1m0s
[13:58:31.581] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:147:0)
[13:58:31.581] Timer tick, asking Buildbucket for the build status
[13:58:31.930] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:31.930] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:148:0) after 1m0s
[13:59:32.018] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:148:0)
[13:59:32.018] Timer tick, asking Buildbucket for the build status
[13:59:32.119] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:32.119] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:149:0) after 1m0s
[14:00:32.154] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:149:0)
[14:00:32.154] Timer tick, asking Buildbucket for the build status
[14:00:32.405] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:32.405] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:150:0) after 1m0s
[14:01:32.428] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:150:0)
[14:01:32.428] Timer tick, asking Buildbucket for the build status
[14:01:32.792] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:32.792] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:151:0) after 1m0s
[14:02:36.815] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:151:0)
[14:02:36.815] Timer tick, asking Buildbucket for the build status
[14:02:36.947] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:36.947] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:152:0) after 1m0s
[14:03:36.971] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:152:0)
[14:03:36.971] Timer tick, asking Buildbucket for the build status
[14:03:37.028] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:37.028] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:153:0) after 1m0s
[14:04:37.075] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:153:0)
[14:04:37.075] Timer tick, asking Buildbucket for the build status
[14:04:37.169] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:37.169] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:154:0) after 1m0s
[14:05:37.257] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:154:0)
[14:05:37.257] Timer tick, asking Buildbucket for the build status
[14:05:37.605] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:37.605] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:155:0) after 1m0s
[14:06:37.625] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:155:0)
[14:06:37.625] Timer tick, asking Buildbucket for the build status
[14:06:37.692] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:37.692] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:156:0) after 1m0s
[14:07:37.777] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:156:0)
[14:07:37.777] Timer tick, asking Buildbucket for the build status
[14:07:37.900] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:37.900] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:157:0) after 1m0s
[14:08:37.953] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:157:0)
[14:08:37.953] Timer tick, asking Buildbucket for the build status
[14:08:38.060] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:38.060] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:158:0) after 1m0s
[14:09:38.141] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:158:0)
[14:09:38.141] Timer tick, asking Buildbucket for the build status
[14:09:38.252] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:38.252] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:159:0) after 1m0s
[14:10:38.299] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:159:0)
[14:10:38.299] Timer tick, asking Buildbucket for the build status
[14:10:38.400] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:38.400] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:160:0) after 1m0s
[14:11:38.425] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:160:0)
[14:11:38.425] Timer tick, asking Buildbucket for the build status
[14:11:38.533] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:38.533] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:161:0) after 1m0s
[14:12:38.562] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:161:0)
[14:12:38.562] Timer tick, asking Buildbucket for the build status
[14:12:38.640] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:38.640] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:162:0) after 1m0s
[14:13:38.671] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:162:0)
[14:13:38.671] Timer tick, asking Buildbucket for the build status
[14:13:38.737] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:38.737] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:163:0) after 1m0s
[14:14:38.829] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:163:0)
[14:14:38.829] Timer tick, asking Buildbucket for the build status
[14:14:38.986] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:38.986] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:164:0) after 1m0s
[14:15:39.074] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:164:0)
[14:15:39.074] Timer tick, asking Buildbucket for the build status
[14:15:39.183] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:39.183] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:165:0) after 1m0s
[14:16:39.211] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:165:0)
[14:16:39.211] Timer tick, asking Buildbucket for the build status
[14:16:39.517] Build 8862299543924892864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:39.517] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027729605919747120:166:0) after 1m0s
[14:17:24.591] Received PubSub notification, asking Buildbucket for the build status
[14:17:24.650] Build 8862299543924892864: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:17:24.651] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606659443668017",
"created_by": "project:chromium",
"created_ts": "1606649562868815",
"id": "8862299543924892864",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"428cca58d5b0e677713cad92b0232a997c47cb75\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"428cca58d5b0e677713cad92b0232a997c47cb75\", \"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-9-fys9\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1022, \"got_angle_revision\": \"59aa1ef1975ae2d263da864f111eaaa7cdb4f9f2\", \"got_dawn_revision\": \"d8bb0bb7c735173219d7a8ffec18eee1813ff302\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"428cca58d5b0e677713cad92b0232a997c47cb75\", \"got_revision_cp\": \"refs/heads/master@{#831719}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"8d791670ba247239ef6ce9091183f11f8c5f0825\", \"got_v8_revision_cp\": \"refs/heads/8.9.65@{#1}\", \"got_webrtc_revision\": \"bcca3b08a90fccaa6dd8d86f6079f0ca0553ab4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32715}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"428cca58d5b0e677713cad92b0232a997c47cb75\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-9-fys9\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606649653284603",
"status": "COMPLETED",
"status_changed_ts": "1606659443964441",
"tags": [
"build_address:luci.chromium.ci/win32-official/1022",
"builder:win32-official",
"buildset:commit/git/428cca58d5b0e677713cad92b0232a997c47cb75",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/428cca58d5b0e677713cad92b0232a997c47cb75",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027729605919747120",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8862299543924892864/+/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:502c98519eb99510",
"user_agent:luci-scheduler"
],
"updated_ts": "1606659443968617",
"url": "https://ci.chromium.org/b/8862299543924892864",
"utcnow_ts": "1606659444641339"
}
}
[14:17:24.651] Invocation finished in 2h44m43.068520433s with status SUCCEEDED