[14:08:30.796] New invocation is queued and will start shortly
[14:08:32.034] Starting the invocation (attempt 1)
[14:08:32.078] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:08:32.078] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:08:32.078] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030618905500777232",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3201204fb69e26744eb57659f13e2d8b026c9280\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9030618905500777232",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/3201204fb69e26744eb57659f13e2d8b026c9280",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3201204fb69e26744eb57659f13e2d8b026c9280",
"gitiles_ref:refs/heads/master"
]
}
[14:08:32.991] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603894112193899",
"id": "8865188843370880656",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3201204fb69e26744eb57659f13e2d8b026c9280\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603894112825174",
"tags": [
"build_address:luci.chromium.ci/win32-official/831",
"builder:win32-official",
"buildset:commit/git/3201204fb69e26744eb57659f13e2d8b026c9280",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3201204fb69e26744eb57659f13e2d8b026c9280",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030618905500777232",
"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/8865188843370880656/+/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": "1603894112825289",
"url": "https://ci.chromium.org/b/8865188843370880656",
"utcnow_ts": "1603894112982037"
}
}
[14:08:32.991] Task URL: https://ci.chromium.org/b/8865188843370880656
[14:08:32.991] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:2:0) after 1m0s
[14:09:33.021] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:2:0)
[14:09:33.021] Timer tick, asking Buildbucket for the build status
[14:09:33.222] Build 8865188843370880656: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:09:33.222] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:3:0) after 1m0s
[14:10:33.253] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:3:0)
[14:10:33.253] Timer tick, asking Buildbucket for the build status
[14:10:33.641] Build 8865188843370880656: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:10:33.641] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:4:0) after 1m0s
[14:11:33.755] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:4:0)
[14:11:33.755] Timer tick, asking Buildbucket for the build status
[14:11:34.412] Build 8865188843370880656: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:11:34.412] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:5:0) after 1m0s
[14:12:34.472] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:5:0)
[14:12:34.472] Timer tick, asking Buildbucket for the build status
[14:12:34.573] Build 8865188843370880656: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:12:34.573] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:6:0) after 1m0s
[14:13:19.420] Received PubSub notification, asking Buildbucket for the build status
[14:13:19.514] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:34.603] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:6:0)
[14:13:34.603] Timer tick, asking Buildbucket for the build status
[14:13:34.725] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:34.725] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:8:0) after 1m0s
[14:14:34.839] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:8:0)
[14:14:34.839] Timer tick, asking Buildbucket for the build status
[14:14:34.944] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:34.944] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:9:0) after 1m0s
[14:15:34.978] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:9:0)
[14:15:34.978] Timer tick, asking Buildbucket for the build status
[14:15:35.084] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:35.084] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:10:0) after 1m0s
[14:16:35.181] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:10:0)
[14:16:35.181] Timer tick, asking Buildbucket for the build status
[14:16:35.498] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:35.498] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:11:0) after 1m0s
[14:17:35.590] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:11:0)
[14:17:35.590] Timer tick, asking Buildbucket for the build status
[14:17:35.694] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:35.694] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:12:0) after 1m0s
[14:18:35.759] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:12:0)
[14:18:35.759] Timer tick, asking Buildbucket for the build status
[14:18:36.024] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:36.024] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:13:0) after 1m0s
[14:19:36.098] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:13:0)
[14:19:36.098] Timer tick, asking Buildbucket for the build status
[14:19:36.343] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:36.343] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:14:0) after 1m0s
[14:20:37.664] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:14:0)
[14:20:37.664] Timer tick, asking Buildbucket for the build status
[14:20:37.880] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:37.880] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:15:0) after 1m0s
[14:21:37.921] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:15:0)
[14:21:37.921] Timer tick, asking Buildbucket for the build status
[14:21:38.084] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:38.084] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:16:0) after 1m0s
[14:22:38.098] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:16:0)
[14:22:38.098] Timer tick, asking Buildbucket for the build status
[14:22:38.166] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:38.166] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:17:0) after 1m0s
[14:23:38.221] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:17:0)
[14:23:38.221] Timer tick, asking Buildbucket for the build status
[14:23:38.616] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:38.616] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:18:0) after 1m0s
[14:24:38.642] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:18:0)
[14:24:38.642] Timer tick, asking Buildbucket for the build status
[14:24:38.725] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:38.725] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:19:0) after 1m0s
[14:25:38.749] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:19:0)
[14:25:38.749] Timer tick, asking Buildbucket for the build status
[14:25:38.851] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:38.851] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:20:0) after 1m0s
[14:26:38.878] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:20:0)
[14:26:38.878] Timer tick, asking Buildbucket for the build status
[14:26:38.949] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:38.949] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:21:0) after 1m0s
[14:27:38.998] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:21:0)
[14:27:38.998] Timer tick, asking Buildbucket for the build status
[14:27:39.301] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:39.301] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:22:0) after 1m0s
[14:28:39.406] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:22:0)
[14:28:39.406] Timer tick, asking Buildbucket for the build status
[14:28:39.572] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:39.572] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:23:0) after 1m0s
[14:29:39.629] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:23:0)
[14:29:39.629] Timer tick, asking Buildbucket for the build status
[14:29:39.725] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:39.725] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:24:0) after 1m0s
[14:30:39.772] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:24:0)
[14:30:39.772] Timer tick, asking Buildbucket for the build status
[14:30:39.844] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:39.844] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:25:0) after 1m0s
[14:31:39.933] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:25:0)
[14:31:39.933] Timer tick, asking Buildbucket for the build status
[14:31:40.123] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:40.123] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:26:0) after 1m0s
[14:32:40.629] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:26:0)
[14:32:40.629] Timer tick, asking Buildbucket for the build status
[14:32:40.718] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:40.718] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:27:0) after 1m0s
[14:33:40.773] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:27:0)
[14:33:40.773] Timer tick, asking Buildbucket for the build status
[14:33:40.891] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:40.891] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:28:0) after 1m0s
[14:34:41.368] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:28:0)
[14:34:41.368] Timer tick, asking Buildbucket for the build status
[14:34:41.456] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:41.456] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:29:0) after 1m0s
[14:35:41.575] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:29:0)
[14:35:41.575] Timer tick, asking Buildbucket for the build status
[14:35:41.757] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:41.757] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:30:0) after 1m0s
[14:36:42.127] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:30:0)
[14:36:42.127] Timer tick, asking Buildbucket for the build status
[14:36:42.369] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:42.369] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:31:0) after 1m0s
[14:37:42.542] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:31:0)
[14:37:42.542] Timer tick, asking Buildbucket for the build status
[14:37:42.638] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:42.639] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:32:0) after 1m0s
[14:38:42.777] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:32:0)
[14:38:42.777] Timer tick, asking Buildbucket for the build status
[14:38:42.920] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:42.920] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:33:0) after 1m0s
[14:39:43.017] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:33:0)
[14:39:43.017] Timer tick, asking Buildbucket for the build status
[14:39:43.164] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:43.164] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:34:0) after 1m0s
[14:40:43.243] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:34:0)
[14:40:43.243] Timer tick, asking Buildbucket for the build status
[14:40:43.738] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:43.738] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:35:0) after 1m0s
[14:41:43.962] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:35:0)
[14:41:43.962] Timer tick, asking Buildbucket for the build status
[14:41:44.030] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:44.030] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:36:0) after 1m0s
[14:42:44.144] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:36:0)
[14:42:44.144] Timer tick, asking Buildbucket for the build status
[14:42:44.800] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:44.800] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:37:0) after 1m0s
[14:43:44.878] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:37:0)
[14:43:44.878] Timer tick, asking Buildbucket for the build status
[14:43:45.047] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:45.047] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:38:0) after 1m0s
[14:44:45.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:38:0)
[14:44:45.076] Timer tick, asking Buildbucket for the build status
[14:44:45.238] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:45.238] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:39:0) after 1m0s
[14:45:45.248] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:39:0)
[14:45:45.248] Timer tick, asking Buildbucket for the build status
[14:45:45.361] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:45.361] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:40:0) after 1m0s
[14:46:45.427] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:40:0)
[14:46:45.427] Timer tick, asking Buildbucket for the build status
[14:46:45.613] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:45.613] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:41:0) after 1m0s
[14:47:45.810] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:41:0)
[14:47:45.810] Timer tick, asking Buildbucket for the build status
[14:47:46.187] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:46.187] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:42:0) after 1m0s
[14:48:46.342] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:42:0)
[14:48:46.342] Timer tick, asking Buildbucket for the build status
[14:48:46.473] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:46.473] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:43:0) after 1m0s
[14:49:46.636] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:43:0)
[14:49:46.636] Timer tick, asking Buildbucket for the build status
[14:49:46.724] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:46.724] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:44:0) after 1m0s
[14:50:46.831] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:44:0)
[14:50:46.831] Timer tick, asking Buildbucket for the build status
[14:50:46.893] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:46.893] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:45:0) after 1m0s
[14:51:47.090] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:45:0)
[14:51:47.090] Timer tick, asking Buildbucket for the build status
[14:51:47.234] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:47.234] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:46:0) after 1m0s
[14:52:47.336] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:46:0)
[14:52:47.336] Timer tick, asking Buildbucket for the build status
[14:52:47.418] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:47.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:47:0) after 1m0s
[14:53:47.541] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:47:0)
[14:53:47.541] Timer tick, asking Buildbucket for the build status
[14:53:47.667] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:47.667] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:48:0) after 1m0s
[14:54:47.676] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:48:0)
[14:54:47.676] Timer tick, asking Buildbucket for the build status
[14:54:47.749] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:47.749] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:49:0) after 1m0s
[14:55:47.888] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:49:0)
[14:55:47.888] Timer tick, asking Buildbucket for the build status
[14:55:49.972] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:49.972] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:50:0) after 1m0s
[14:56:50.193] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:50:0)
[14:56:50.193] Timer tick, asking Buildbucket for the build status
[14:56:50.545] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:50.545] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:51:0) after 1m0s
[14:57:50.652] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:51:0)
[14:57:50.652] Timer tick, asking Buildbucket for the build status
[14:57:50.729] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:50.729] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:52:0) after 1m0s
[14:58:50.838] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:52:0)
[14:58:50.838] Timer tick, asking Buildbucket for the build status
[14:58:51.250] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:51.250] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:53:0) after 1m0s
[14:59:51.354] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:53:0)
[14:59:51.354] Timer tick, asking Buildbucket for the build status
[14:59:51.653] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:51.653] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:54:0) after 1m0s
[15:00:52.645] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:54:0)
[15:00:52.645] Timer tick, asking Buildbucket for the build status
[15:00:53.240] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:53.240] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:55:0) after 1m0s
[15:01:53.763] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:55:0)
[15:01:53.763] Timer tick, asking Buildbucket for the build status
[15:01:53.909] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:53.909] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:56:0) after 1m0s
[15:02:53.907] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:56:0)
[15:02:53.907] Timer tick, asking Buildbucket for the build status
[15:02:54.003] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:54.003] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:57:0) after 1m0s
[15:03:54.174] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:57:0)
[15:03:54.174] Timer tick, asking Buildbucket for the build status
[15:03:54.277] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:54.277] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:58:0) after 1m0s
[15:04:54.316] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:58:0)
[15:04:54.316] Timer tick, asking Buildbucket for the build status
[15:04:54.418] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:54.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:59:0) after 1m0s
[15:05:54.545] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:59:0)
[15:05:54.545] Timer tick, asking Buildbucket for the build status
[15:05:54.688] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:54.688] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:60:0) after 1m0s
[15:06:54.783] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:60:0)
[15:06:54.783] Timer tick, asking Buildbucket for the build status
[15:06:54.866] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:54.866] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:61:0) after 1m0s
[15:07:54.943] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:61:0)
[15:07:54.943] Timer tick, asking Buildbucket for the build status
[15:07:55.024] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:55.024] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:62:0) after 1m0s
[15:08:55.153] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:62:0)
[15:08:55.153] Timer tick, asking Buildbucket for the build status
[15:08:55.288] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:55.288] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:63:0) after 1m0s
[15:09:55.414] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:63:0)
[15:09:55.414] Timer tick, asking Buildbucket for the build status
[15:09:55.488] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:55.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:64:0) after 1m0s
[15:10:55.653] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:64:0)
[15:10:55.653] Timer tick, asking Buildbucket for the build status
[15:10:55.728] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:55.728] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:65:0) after 1m0s
[15:11:56.149] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:65:0)
[15:11:56.149] Timer tick, asking Buildbucket for the build status
[15:11:56.239] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:56.239] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:66:0) after 1m0s
[15:12:56.596] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:66:0)
[15:12:56.596] Timer tick, asking Buildbucket for the build status
[15:12:57.251] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:57.251] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:67:0) after 1m0s
[15:13:57.470] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:67:0)
[15:13:57.470] Timer tick, asking Buildbucket for the build status
[15:13:57.589] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:57.589] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:68:0) after 1m0s
[15:14:57.852] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:68:0)
[15:14:57.852] Timer tick, asking Buildbucket for the build status
[15:14:58.103] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:58.104] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:69:0) after 1m0s
[15:15:58.183] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:69:0)
[15:15:58.183] Timer tick, asking Buildbucket for the build status
[15:15:58.264] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:58.264] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:70:0) after 1m0s
[15:16:58.355] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:70:0)
[15:16:58.355] Timer tick, asking Buildbucket for the build status
[15:16:58.422] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:58.422] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:71:0) after 1m0s
[15:17:59.487] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:71:0)
[15:17:59.487] Timer tick, asking Buildbucket for the build status
[15:17:59.707] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:59.707] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:72:0) after 1m0s
[15:18:59.845] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:72:0)
[15:18:59.845] Timer tick, asking Buildbucket for the build status
[15:19:00.423] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:00.423] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:73:0) after 1m0s
[15:20:00.580] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:73:0)
[15:20:00.580] Timer tick, asking Buildbucket for the build status
[15:20:00.650] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:00.650] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:74:0) after 1m0s
[15:21:01.877] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:74:0)
[15:21:01.877] Timer tick, asking Buildbucket for the build status
[15:21:01.941] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:01.941] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:75:0) after 1m0s
[15:22:02.000] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:75:0)
[15:22:02.000] Timer tick, asking Buildbucket for the build status
[15:22:02.098] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:02.098] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:76:0) after 1m0s
[15:23:02.772] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:76:0)
[15:23:02.772] Timer tick, asking Buildbucket for the build status
[15:23:03.066] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:03.066] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:77:0) after 1m0s
[15:24:03.093] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:77:0)
[15:24:03.093] Timer tick, asking Buildbucket for the build status
[15:24:03.406] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:03.406] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:78:0) after 1m0s
[15:25:03.527] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:78:0)
[15:25:03.527] Timer tick, asking Buildbucket for the build status
[15:25:03.622] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:03.622] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:79:0) after 1m0s
[15:26:03.700] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:79:0)
[15:26:03.700] Timer tick, asking Buildbucket for the build status
[15:26:03.782] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:03.782] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:80:0) after 1m0s
[15:27:03.820] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:80:0)
[15:27:03.820] Timer tick, asking Buildbucket for the build status
[15:27:04.026] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:04.026] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:81:0) after 1m0s
[15:28:04.057] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:81:0)
[15:28:04.057] Timer tick, asking Buildbucket for the build status
[15:28:04.177] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:04.177] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:82:0) after 1m0s
[15:29:04.231] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:82:0)
[15:29:04.231] Timer tick, asking Buildbucket for the build status
[15:29:04.358] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:04.358] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:83:0) after 1m0s
[15:30:04.579] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:83:0)
[15:30:04.580] Timer tick, asking Buildbucket for the build status
[15:30:06.429] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:06.429] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:84:0) after 1m0s
[15:31:06.507] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:84:0)
[15:31:06.507] Timer tick, asking Buildbucket for the build status
[15:31:06.798] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:06.798] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:85:0) after 1m0s
[15:32:06.789] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:85:0)
[15:32:06.789] Timer tick, asking Buildbucket for the build status
[15:32:06.878] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:06.878] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:86:0) after 1m0s
[15:33:06.965] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:86:0)
[15:33:06.965] Timer tick, asking Buildbucket for the build status
[15:33:07.228] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:07.228] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:87:0) after 1m0s
[15:34:07.254] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:87:0)
[15:34:07.254] Timer tick, asking Buildbucket for the build status
[15:34:07.502] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:07.502] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:88:0) after 1m0s
[15:35:07.555] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:88:0)
[15:35:07.555] Timer tick, asking Buildbucket for the build status
[15:35:07.655] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:07.655] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:89:0) after 1m0s
[15:36:07.756] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:89:0)
[15:36:07.756] Timer tick, asking Buildbucket for the build status
[15:36:08.116] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:08.116] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:90:0) after 1m0s
[15:37:08.166] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:90:0)
[15:37:08.166] Timer tick, asking Buildbucket for the build status
[15:37:08.241] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:08.241] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:91:0) after 1m0s
[15:38:08.329] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:91:0)
[15:38:08.329] Timer tick, asking Buildbucket for the build status
[15:38:08.424] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:08.424] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:92:0) after 1m0s
[15:39:08.451] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:92:0)
[15:39:08.451] Timer tick, asking Buildbucket for the build status
[15:39:08.609] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:08.609] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:93:0) after 1m0s
[15:40:08.690] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:93:0)
[15:40:08.690] Timer tick, asking Buildbucket for the build status
[15:40:08.755] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:08.755] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:94:0) after 1m0s
[15:41:08.817] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:94:0)
[15:41:08.817] Timer tick, asking Buildbucket for the build status
[15:41:08.892] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:08.892] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:95:0) after 1m0s
[15:42:08.918] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:95:0)
[15:42:08.918] Timer tick, asking Buildbucket for the build status
[15:42:09.138] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:09.138] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:96:0) after 1m0s
[15:43:09.208] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:96:0)
[15:43:09.208] Timer tick, asking Buildbucket for the build status
[15:43:09.339] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:09.339] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:97:0) after 1m0s
[15:44:09.344] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:97:0)
[15:44:09.344] Timer tick, asking Buildbucket for the build status
[15:44:09.825] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:09.825] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:98:0) after 1m0s
[15:45:09.875] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:98:0)
[15:45:09.875] Timer tick, asking Buildbucket for the build status
[15:45:10.082] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:10.082] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:99:0) after 1m0s
[15:46:10.126] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:99:0)
[15:46:10.126] Timer tick, asking Buildbucket for the build status
[15:46:10.207] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:10.207] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:100:0) after 1m0s
[15:47:10.295] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:100:0)
[15:47:10.295] Timer tick, asking Buildbucket for the build status
[15:47:10.369] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:10.369] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:101:0) after 1m0s
[15:48:10.497] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:101:0)
[15:48:10.497] Timer tick, asking Buildbucket for the build status
[15:48:10.584] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:10.584] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:102:0) after 1m0s
[15:49:10.677] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:102:0)
[15:49:10.678] Timer tick, asking Buildbucket for the build status
[15:49:10.786] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:10.786] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:103:0) after 1m0s
[15:50:10.872] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:103:0)
[15:50:10.872] Timer tick, asking Buildbucket for the build status
[15:50:12.515] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:12.515] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:104:0) after 1m0s
[15:51:12.573] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:104:0)
[15:51:12.573] Timer tick, asking Buildbucket for the build status
[15:51:12.640] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:12.640] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:105:0) after 1m0s
[15:52:12.774] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:105:0)
[15:52:12.774] Timer tick, asking Buildbucket for the build status
[15:52:12.851] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:12.851] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:106:0) after 1m0s
[15:53:12.913] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:106:0)
[15:53:12.913] Timer tick, asking Buildbucket for the build status
[15:53:13.310] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:13.311] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:107:0) after 1m0s
[15:54:13.382] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:107:0)
[15:54:13.382] Timer tick, asking Buildbucket for the build status
[15:54:13.552] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:13.553] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:108:0) after 1m0s
[15:55:13.697] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:108:0)
[15:55:13.697] Timer tick, asking Buildbucket for the build status
[15:55:13.997] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:13.997] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:109:0) after 1m0s
[15:56:14.209] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:109:0)
[15:56:14.209] Timer tick, asking Buildbucket for the build status
[15:56:14.400] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:14.400] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:110:0) after 1m0s
[15:57:14.475] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:110:0)
[15:57:14.475] Timer tick, asking Buildbucket for the build status
[15:57:14.548] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:14.548] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:111:0) after 1m0s
[15:58:14.636] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:111:0)
[15:58:14.636] Timer tick, asking Buildbucket for the build status
[15:58:14.811] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:14.811] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:112:0) after 1m0s
[15:59:14.846] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:112:0)
[15:59:14.846] Timer tick, asking Buildbucket for the build status
[15:59:14.985] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:14.985] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:113:0) after 1m0s
[16:00:15.045] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:113:0)
[16:00:15.045] Timer tick, asking Buildbucket for the build status
[16:00:15.237] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:15.237] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:114:0) after 1m0s
[16:01:15.248] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:114:0)
[16:01:15.248] Timer tick, asking Buildbucket for the build status
[16:01:15.552] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:15.552] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:115:0) after 1m0s
[16:02:15.607] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:115:0)
[16:02:15.607] Timer tick, asking Buildbucket for the build status
[16:02:15.743] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:15.743] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:116:0) after 1m0s
[16:03:15.806] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:116:0)
[16:03:15.806] Timer tick, asking Buildbucket for the build status
[16:03:16.197] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:16.198] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:117:0) after 1m0s
[16:04:16.207] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:117:0)
[16:04:16.207] Timer tick, asking Buildbucket for the build status
[16:04:16.383] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:16.383] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:118:0) after 1m0s
[16:05:16.452] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:118:0)
[16:05:16.452] Timer tick, asking Buildbucket for the build status
[16:05:16.909] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:16.909] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:119:0) after 1m0s
[16:06:16.961] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:119:0)
[16:06:16.961] Timer tick, asking Buildbucket for the build status
[16:06:17.033] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:17.033] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:120:0) after 1m0s
[16:07:17.056] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:120:0)
[16:07:17.056] Timer tick, asking Buildbucket for the build status
[16:07:17.183] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:17.183] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:121:0) after 1m0s
[16:08:17.288] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:121:0)
[16:08:17.288] Timer tick, asking Buildbucket for the build status
[16:08:17.469] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:17.469] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:122:0) after 1m0s
[16:09:17.588] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:122:0)
[16:09:17.588] Timer tick, asking Buildbucket for the build status
[16:09:17.901] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:17.901] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:123:0) after 1m0s
[16:10:17.959] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:123:0)
[16:10:17.959] Timer tick, asking Buildbucket for the build status
[16:10:18.030] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:18.030] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:124:0) after 1m0s
[16:11:18.078] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:124:0)
[16:11:18.078] Timer tick, asking Buildbucket for the build status
[16:11:18.179] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:18.179] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:125:0) after 1m0s
[16:12:18.293] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:125:0)
[16:12:18.293] Timer tick, asking Buildbucket for the build status
[16:12:18.383] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:18.383] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:126:0) after 1m0s
[16:13:18.441] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:126:0)
[16:13:18.441] Timer tick, asking Buildbucket for the build status
[16:13:18.580] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:18.580] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:127:0) after 1m0s
[16:14:18.624] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:127:0)
[16:14:18.624] Timer tick, asking Buildbucket for the build status
[16:14:18.738] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:18.738] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:128:0) after 1m0s
[16:15:18.806] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:128:0)
[16:15:18.806] Timer tick, asking Buildbucket for the build status
[16:15:18.891] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:18.891] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:129:0) after 1m0s
[16:16:18.901] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:129:0)
[16:16:18.901] Timer tick, asking Buildbucket for the build status
[16:16:19.108] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:19.108] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:130:0) after 1m0s
[16:17:19.097] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:130:0)
[16:17:19.097] Timer tick, asking Buildbucket for the build status
[16:17:19.183] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:19.183] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:131:0) after 1m0s
[16:18:19.277] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:131:0)
[16:18:19.277] Timer tick, asking Buildbucket for the build status
[16:18:19.380] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:19.380] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:132:0) after 1m0s
[16:19:19.470] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:132:0)
[16:19:19.470] Timer tick, asking Buildbucket for the build status
[16:19:19.596] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:19.596] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:133:0) after 1m0s
[16:20:19.659] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:133:0)
[16:20:19.659] Timer tick, asking Buildbucket for the build status
[16:20:20.041] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:20.041] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:134:0) after 1m0s
[16:21:20.046] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:134:0)
[16:21:20.046] Timer tick, asking Buildbucket for the build status
[16:21:20.148] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:20.148] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:135:0) after 1m0s
[16:22:20.175] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:135:0)
[16:22:20.175] Timer tick, asking Buildbucket for the build status
[16:22:20.254] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:20.254] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:136:0) after 1m0s
[16:23:20.345] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:136:0)
[16:23:20.345] Timer tick, asking Buildbucket for the build status
[16:23:20.415] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:20.415] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:137:0) after 1m0s
[16:24:20.427] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:137:0)
[16:24:20.427] Timer tick, asking Buildbucket for the build status
[16:24:20.544] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:20.545] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:138:0) after 1m0s
[16:25:20.602] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:138:0)
[16:25:20.602] Timer tick, asking Buildbucket for the build status
[16:25:20.815] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:20.815] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:139:0) after 1m0s
[16:26:20.907] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:139:0)
[16:26:20.907] Timer tick, asking Buildbucket for the build status
[16:26:20.996] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:20.996] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:140:0) after 1m0s
[16:27:21.053] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:140:0)
[16:27:21.053] Timer tick, asking Buildbucket for the build status
[16:27:21.193] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:21.193] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:141:0) after 1m0s
[16:28:21.209] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:141:0)
[16:28:21.209] Timer tick, asking Buildbucket for the build status
[16:28:21.749] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:21.749] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:142:0) after 1m0s
[16:29:21.915] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:142:0)
[16:29:21.915] Timer tick, asking Buildbucket for the build status
[16:29:22.062] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:22.062] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:143:0) after 1m0s
[16:30:22.203] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:143:0)
[16:30:22.203] Timer tick, asking Buildbucket for the build status
[16:30:22.474] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:22.474] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:144:0) after 1m0s
[16:31:22.550] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:144:0)
[16:31:22.551] Timer tick, asking Buildbucket for the build status
[16:31:22.655] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:22.655] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:145:0) after 1m0s
[16:32:22.747] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:145:0)
[16:32:22.747] Timer tick, asking Buildbucket for the build status
[16:32:22.838] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:22.838] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:146:0) after 1m0s
[16:33:22.879] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:146:0)
[16:33:22.879] Timer tick, asking Buildbucket for the build status
[16:33:22.954] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:22.954] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:147:0) after 1m0s
[16:34:22.995] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:147:0)
[16:34:22.995] Timer tick, asking Buildbucket for the build status
[16:34:23.240] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:23.240] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:148:0) after 1m0s
[16:35:23.301] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:148:0)
[16:35:23.301] Timer tick, asking Buildbucket for the build status
[16:35:23.589] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:23.589] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:149:0) after 1m0s
[16:36:23.657] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:149:0)
[16:36:23.657] Timer tick, asking Buildbucket for the build status
[16:36:23.728] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:23.728] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:150:0) after 1m0s
[16:37:23.777] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:150:0)
[16:37:23.777] Timer tick, asking Buildbucket for the build status
[16:37:23.849] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:23.849] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:151:0) after 1m0s
[16:38:23.839] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:151:0)
[16:38:23.839] Timer tick, asking Buildbucket for the build status
[16:38:23.903] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:23.903] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:152:0) after 1m0s
[16:39:23.935] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:152:0)
[16:39:23.935] Timer tick, asking Buildbucket for the build status
[16:39:24.092] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:24.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:153:0) after 1m0s
[16:40:24.125] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:153:0)
[16:40:24.125] Timer tick, asking Buildbucket for the build status
[16:40:24.284] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:24.284] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:154:0) after 1m0s
[16:41:24.400] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:154:0)
[16:41:24.400] Timer tick, asking Buildbucket for the build status
[16:41:24.493] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:24.493] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:155:0) after 1m0s
[16:42:24.611] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:155:0)
[16:42:24.611] Timer tick, asking Buildbucket for the build status
[16:42:24.801] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:24.801] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:156:0) after 1m0s
[16:43:24.905] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:156:0)
[16:43:24.905] Timer tick, asking Buildbucket for the build status
[16:43:25.282] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:25.282] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:157:0) after 1m0s
[16:44:25.344] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:157:0)
[16:44:25.344] Timer tick, asking Buildbucket for the build status
[16:44:25.478] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:25.478] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:158:0) after 1m0s
[16:45:25.594] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:158:0)
[16:45:25.594] Timer tick, asking Buildbucket for the build status
[16:45:26.372] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:26.372] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:159:0) after 1m0s
[16:46:26.466] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:159:0)
[16:46:26.466] Timer tick, asking Buildbucket for the build status
[16:46:26.568] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:26.568] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:160:0) after 1m0s
[16:47:26.562] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:160:0)
[16:47:26.562] Timer tick, asking Buildbucket for the build status
[16:47:26.665] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:26.665] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:161:0) after 1m0s
[16:48:26.697] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:161:0)
[16:48:26.697] Timer tick, asking Buildbucket for the build status
[16:48:26.765] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:26.766] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:162:0) after 1m0s
[16:49:26.762] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:162:0)
[16:49:26.762] Timer tick, asking Buildbucket for the build status
[16:49:26.888] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:26.888] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:163:0) after 1m0s
[16:50:27.010] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:163:0)
[16:50:27.010] Timer tick, asking Buildbucket for the build status
[16:50:27.327] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:27.327] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:164:0) after 1m0s
[16:51:27.385] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:164:0)
[16:51:27.385] Timer tick, asking Buildbucket for the build status
[16:51:27.525] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:27.525] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:165:0) after 1m0s
[16:52:27.587] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:165:0)
[16:52:27.587] Timer tick, asking Buildbucket for the build status
[16:52:27.877] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:27.877] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:166:0) after 1m0s
[16:53:27.908] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:166:0)
[16:53:27.908] Timer tick, asking Buildbucket for the build status
[16:53:28.099] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:28.099] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:167:0) after 1m0s
[16:54:28.161] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:167:0)
[16:54:28.161] Timer tick, asking Buildbucket for the build status
[16:54:28.272] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:28.272] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:168:0) after 1m0s
[16:55:28.354] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:168:0)
[16:55:28.354] Timer tick, asking Buildbucket for the build status
[16:55:28.442] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:28.442] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:169:0) after 1m0s
[16:56:28.455] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:169:0)
[16:56:28.455] Timer tick, asking Buildbucket for the build status
[16:56:28.570] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:28.570] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:170:0) after 1m0s
[16:57:28.627] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:170:0)
[16:57:28.627] Timer tick, asking Buildbucket for the build status
[16:57:28.696] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:28.696] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:171:0) after 1m0s
[16:58:28.768] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:171:0)
[16:58:28.768] Timer tick, asking Buildbucket for the build status
[16:58:29.179] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:29.179] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:172:0) after 1m0s
[16:59:29.303] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:172:0)
[16:59:29.303] Timer tick, asking Buildbucket for the build status
[16:59:29.480] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:29.480] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:173:0) after 1m0s
[17:00:29.601] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:173:0)
[17:00:29.601] Timer tick, asking Buildbucket for the build status
[17:00:30.049] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:30.049] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:174:0) after 1m0s
[17:01:30.116] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:174:0)
[17:01:30.116] Timer tick, asking Buildbucket for the build status
[17:01:30.258] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:30.258] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:175:0) after 1m0s
[17:02:30.333] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:175:0)
[17:02:30.333] Timer tick, asking Buildbucket for the build status
[17:02:30.623] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:30.623] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:176:0) after 1m0s
[17:03:30.684] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:176:0)
[17:03:30.684] Timer tick, asking Buildbucket for the build status
[17:03:30.783] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:30.783] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:177:0) after 1m0s
[17:04:30.779] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:177:0)
[17:04:30.779] Timer tick, asking Buildbucket for the build status
[17:04:30.971] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:30.971] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:178:0) after 1m0s
[17:05:31.062] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:178:0)
[17:05:31.062] Timer tick, asking Buildbucket for the build status
[17:05:31.278] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:31.278] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:179:0) after 1m0s
[17:06:31.343] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:179:0)
[17:06:31.343] Timer tick, asking Buildbucket for the build status
[17:06:31.456] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:31.456] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:180:0) after 1m0s
[17:07:31.486] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:180:0)
[17:07:31.486] Timer tick, asking Buildbucket for the build status
[17:07:31.728] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:31.728] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:181:0) after 1m0s
[17:08:31.792] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:181:0)
[17:08:31.792] Timer tick, asking Buildbucket for the build status
[17:08:31.888] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:31.888] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:182:0) after 1m0s
[17:09:31.934] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:182:0)
[17:09:31.934] Timer tick, asking Buildbucket for the build status
[17:09:32.106] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:32.106] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:183:0) after 1m0s
[17:10:32.178] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:183:0)
[17:10:32.178] Timer tick, asking Buildbucket for the build status
[17:10:32.510] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:32.510] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:184:0) after 1m0s
[17:11:32.607] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:184:0)
[17:11:32.607] Timer tick, asking Buildbucket for the build status
[17:11:32.719] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:32.719] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:185:0) after 1m0s
[17:12:32.769] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:185:0)
[17:12:32.769] Timer tick, asking Buildbucket for the build status
[17:12:33.181] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:33.181] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:186:0) after 1m0s
[17:13:33.184] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:186:0)
[17:13:33.184] Timer tick, asking Buildbucket for the build status
[17:13:33.260] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:33.260] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:187:0) after 1m0s
[17:14:33.301] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:187:0)
[17:14:33.301] Timer tick, asking Buildbucket for the build status
[17:14:33.445] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:33.445] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:188:0) after 1m0s
[17:15:33.479] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:188:0)
[17:15:33.479] Timer tick, asking Buildbucket for the build status
[17:15:33.572] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:33.572] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:189:0) after 1m0s
[17:16:33.608] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:189:0)
[17:16:33.608] Timer tick, asking Buildbucket for the build status
[17:16:33.764] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:33.764] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:190:0) after 1m0s
[17:17:33.806] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:190:0)
[17:17:33.806] Timer tick, asking Buildbucket for the build status
[17:17:33.969] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:33.969] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:191:0) after 1m0s
[17:18:34.023] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:191:0)
[17:18:34.023] Timer tick, asking Buildbucket for the build status
[17:18:34.126] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:34.126] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:192:0) after 1m0s
[17:19:34.233] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:192:0)
[17:19:34.233] Timer tick, asking Buildbucket for the build status
[17:19:34.488] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:34.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:193:0) after 1m0s
[17:20:34.536] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:193:0)
[17:20:34.537] Timer tick, asking Buildbucket for the build status
[17:20:34.747] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:34.748] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:194:0) after 1m0s
[17:21:34.755] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:194:0)
[17:21:34.755] Timer tick, asking Buildbucket for the build status
[17:21:34.854] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:34.854] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:195:0) after 1m0s
[17:22:34.853] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:195:0)
[17:22:34.853] Timer tick, asking Buildbucket for the build status
[17:22:34.935] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:34.935] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:196:0) after 1m0s
[17:23:34.994] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:196:0)
[17:23:34.994] Timer tick, asking Buildbucket for the build status
[17:23:35.120] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:35.120] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:197:0) after 1m0s
[17:24:35.153] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:197:0)
[17:24:35.153] Timer tick, asking Buildbucket for the build status
[17:24:35.274] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:35.274] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:198:0) after 1m0s
[17:25:35.370] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:198:0)
[17:25:35.370] Timer tick, asking Buildbucket for the build status
[17:25:35.698] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:35.698] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:199:0) after 1m0s
[17:26:35.751] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:199:0)
[17:26:35.751] Timer tick, asking Buildbucket for the build status
[17:26:35.841] Build 8865188843370880656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:35.841] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030618905500777232:200:0) after 1m0s
[17:27:27.119] Received PubSub notification, asking Buildbucket for the build status
[17:27:27.225] Build 8865188843370880656: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:27:27.225] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603906045984774",
"created_by": "project:chromium",
"created_ts": "1603894112193899",
"id": "8865188843370880656",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3201204fb69e26744eb57659f13e2d8b026c9280\"}}",
"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\": \"3201204fb69e26744eb57659f13e2d8b026c9280\", \"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-1-8fme\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 831, \"got_angle_revision\": \"b5ba693316fdd5e680f2bb4cf1a2f9bed589d8e2\", \"got_dawn_revision\": \"47ee61679da09d9ec11f3da5b9b124a0c707c434\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"3201204fb69e26744eb57659f13e2d8b026c9280\", \"got_revision_cp\": \"refs/heads/master@{#821703}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"ecbac8fdbca73296e250a38605a71c8269f0eeff\", \"got_v8_revision_cp\": \"refs/heads/8.8.197@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3201204fb69e26744eb57659f13e2d8b026c9280\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_1daffed6dde8d2010ab2a7aee555237ff999d968966bfcf42bbce4ac98bb7a23_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-1-8fme\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603894397881763",
"status": "COMPLETED",
"status_changed_ts": "1603906046644615",
"tags": [
"build_address:luci.chromium.ci/win32-official/831",
"builder:win32-official",
"buildset:commit/git/3201204fb69e26744eb57659f13e2d8b026c9280",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3201204fb69e26744eb57659f13e2d8b026c9280",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030618905500777232",
"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/8865188843370880656/+/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:4f885b7c36a80f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603906046654854",
"url": "https://ci.chromium.org/b/8865188843370880656",
"utcnow_ts": "1603906047216351"
}
}
[17:27:27.225] Invocation finished in 3h18m56.446167019s with status SUCCEEDED