[14:44:13.812] New invocation is queued and will start shortly
[14:44:14.997] Starting the invocation (attempt 1)
[14:44:15.032] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:44:15.032] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:44:15.032] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025996213104720064",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"47cf2e55df353230cd866d8971b4f9526513bd61\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9025996213104720064",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/47cf2e55df353230cd866d8971b4f9526513bd61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/47cf2e55df353230cd866d8971b4f9526513bd61",
"gitiles_ref:refs/heads/master"
]
}
[14:44:15.600] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608302655126726",
"id": "8860566151056462976",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"47cf2e55df353230cd866d8971b4f9526513bd61\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608302655486592",
"tags": [
"build_address:luci.chromium.ci/win32-official/1135",
"builder:win32-official",
"buildset:commit/git/47cf2e55df353230cd866d8971b4f9526513bd61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/47cf2e55df353230cd866d8971b4f9526513bd61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025996213104720064",
"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/8860566151056462976/+/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": "1608302655486733",
"url": "https://ci.chromium.org/b/8860566151056462976",
"utcnow_ts": "1608302655593102"
}
}
[14:44:15.600] Task URL: https://ci.chromium.org/b/8860566151056462976
[14:44:15.600] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:2:0) after 1m0s
[14:44:44.485] Received PubSub notification, asking Buildbucket for the build status
[14:44:45.333] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:15.772] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:2:0)
[14:45:15.772] Timer tick, asking Buildbucket for the build status
[14:45:15.865] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:15.865] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:4:0) after 1m0s
[14:46:15.918] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:4:0)
[14:46:15.918] Timer tick, asking Buildbucket for the build status
[14:46:15.996] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:15.997] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:5:0) after 1m0s
[14:47:16.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:5:0)
[14:47:16.084] Timer tick, asking Buildbucket for the build status
[14:47:16.166] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:16.166] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:6:0) after 1m0s
[14:48:16.196] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:6:0)
[14:48:16.196] Timer tick, asking Buildbucket for the build status
[14:48:16.344] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:16.344] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:7:0) after 1m0s
[14:49:16.347] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:7:0)
[14:49:16.347] Timer tick, asking Buildbucket for the build status
[14:49:16.450] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:16.450] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:8:0) after 1m0s
[14:50:16.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:8:0)
[14:50:16.508] Timer tick, asking Buildbucket for the build status
[14:50:16.577] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:16.577] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:9:0) after 1m0s
[14:51:16.682] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:9:0)
[14:51:16.683] Timer tick, asking Buildbucket for the build status
[14:51:16.748] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:16.748] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:10:0) after 1m0s
[14:52:16.799] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:10:0)
[14:52:16.799] Timer tick, asking Buildbucket for the build status
[14:52:16.869] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:16.869] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:11:0) after 1m0s
[14:53:16.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:11:0)
[14:53:16.983] Timer tick, asking Buildbucket for the build status
[14:53:17.056] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:17.056] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:12:0) after 1m0s
[14:54:17.092] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:12:0)
[14:54:17.092] Timer tick, asking Buildbucket for the build status
[14:54:17.235] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:17.235] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:13:0) after 1m0s
[14:55:17.309] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:13:0)
[14:55:17.309] Timer tick, asking Buildbucket for the build status
[14:55:17.377] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:17.378] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:14:0) after 1m0s
[14:56:17.401] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:14:0)
[14:56:17.401] Timer tick, asking Buildbucket for the build status
[14:56:17.468] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:17.468] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:15:0) after 1m0s
[14:57:17.510] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:15:0)
[14:57:17.510] Timer tick, asking Buildbucket for the build status
[14:57:17.569] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:17.569] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:16:0) after 1m0s
[14:58:17.591] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:16:0)
[14:58:17.591] Timer tick, asking Buildbucket for the build status
[14:58:17.656] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:17.656] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:17:0) after 1m0s
[14:59:17.631] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:17:0)
[14:59:17.631] Timer tick, asking Buildbucket for the build status
[14:59:17.733] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:17.734] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:18:0) after 1m0s
[15:00:18.683] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:18:0)
[15:00:18.683] Timer tick, asking Buildbucket for the build status
[15:00:18.933] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:18.933] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:19:0) after 1m0s
[15:01:19.119] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:19:0)
[15:01:19.119] Timer tick, asking Buildbucket for the build status
[15:01:19.188] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:19.188] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:20:0) after 1m0s
[15:02:19.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:20:0)
[15:02:19.263] Timer tick, asking Buildbucket for the build status
[15:02:19.409] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:19.409] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:21:0) after 1m0s
[15:03:19.476] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:21:0)
[15:03:19.476] Timer tick, asking Buildbucket for the build status
[15:03:19.939] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:19.939] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:22:0) after 1m0s
[15:04:20.120] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:22:0)
[15:04:20.120] Timer tick, asking Buildbucket for the build status
[15:04:20.308] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:20.308] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:23:0) after 1m0s
[15:05:20.435] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:23:0)
[15:05:20.436] Timer tick, asking Buildbucket for the build status
[15:05:20.512] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:20.512] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:24:0) after 1m0s
[15:06:20.557] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:24:0)
[15:06:20.557] Timer tick, asking Buildbucket for the build status
[15:06:20.730] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:20.730] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:25:0) after 1m0s
[15:07:20.885] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:25:0)
[15:07:20.885] Timer tick, asking Buildbucket for the build status
[15:07:21.074] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:21.074] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:26:0) after 1m0s
[15:08:21.252] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:26:0)
[15:08:21.252] Timer tick, asking Buildbucket for the build status
[15:08:21.328] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:21.328] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:27:0) after 1m0s
[15:09:21.383] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:27:0)
[15:09:21.383] Timer tick, asking Buildbucket for the build status
[15:09:21.495] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:21.495] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:28:0) after 1m0s
[15:10:21.612] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:28:0)
[15:10:21.612] Timer tick, asking Buildbucket for the build status
[15:10:21.832] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:21.832] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:29:0) after 1m0s
[15:11:21.886] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:29:0)
[15:11:21.886] Timer tick, asking Buildbucket for the build status
[15:11:22.106] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:22.106] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:30:0) after 1m0s
[15:12:22.288] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:30:0)
[15:12:22.288] Timer tick, asking Buildbucket for the build status
[15:12:22.374] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:22.374] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:31:0) after 1m0s
[15:13:22.464] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:31:0)
[15:13:22.464] Timer tick, asking Buildbucket for the build status
[15:13:22.531] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:22.531] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:32:0) after 1m0s
[15:14:22.609] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:32:0)
[15:14:22.609] Timer tick, asking Buildbucket for the build status
[15:14:22.732] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:22.732] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:33:0) after 1m0s
[15:15:22.765] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:33:0)
[15:15:22.765] Timer tick, asking Buildbucket for the build status
[15:15:22.868] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:22.868] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:34:0) after 1m0s
[15:16:23.034] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:34:0)
[15:16:23.034] Timer tick, asking Buildbucket for the build status
[15:16:23.162] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:23.162] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:35:0) after 1m0s
[15:17:23.275] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:35:0)
[15:17:23.275] Timer tick, asking Buildbucket for the build status
[15:17:23.519] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:23.519] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:36:0) after 1m0s
[15:18:23.582] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:36:0)
[15:18:23.582] Timer tick, asking Buildbucket for the build status
[15:18:23.702] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:23.702] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:37:0) after 1m0s
[15:19:23.805] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:37:0)
[15:19:23.805] Timer tick, asking Buildbucket for the build status
[15:19:23.959] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:23.959] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:38:0) after 1m0s
[15:20:24.025] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:38:0)
[15:20:24.025] Timer tick, asking Buildbucket for the build status
[15:20:24.092] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:24.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:39:0) after 1m0s
[15:21:24.164] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:39:0)
[15:21:24.164] Timer tick, asking Buildbucket for the build status
[15:21:24.247] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:24.247] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:40:0) after 1m0s
[15:22:24.278] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:40:0)
[15:22:24.278] Timer tick, asking Buildbucket for the build status
[15:22:24.335] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:24.335] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:41:0) after 1m0s
[15:23:24.474] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:41:0)
[15:23:24.474] Timer tick, asking Buildbucket for the build status
[15:23:24.924] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:24.924] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:42:0) after 1m0s
[15:24:24.950] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:42:0)
[15:24:24.950] Timer tick, asking Buildbucket for the build status
[15:24:25.047] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:25.047] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:43:0) after 1m0s
[15:25:25.168] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:43:0)
[15:25:25.168] Timer tick, asking Buildbucket for the build status
[15:25:25.248] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:25.248] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:44:0) after 1m0s
[15:26:25.351] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:44:0)
[15:26:25.351] Timer tick, asking Buildbucket for the build status
[15:26:25.420] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:25.420] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:45:0) after 1m0s
[15:27:25.493] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:45:0)
[15:27:25.493] Timer tick, asking Buildbucket for the build status
[15:27:25.636] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:25.636] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:46:0) after 1m0s
[15:28:25.640] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:46:0)
[15:28:25.641] Timer tick, asking Buildbucket for the build status
[15:28:25.705] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:25.705] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:47:0) after 1m0s
[15:29:25.840] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:47:0)
[15:29:25.840] Timer tick, asking Buildbucket for the build status
[15:29:25.938] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:25.938] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:48:0) after 1m0s
[15:30:26.029] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:48:0)
[15:30:26.029] Timer tick, asking Buildbucket for the build status
[15:30:26.116] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:26.116] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:49:0) after 1m0s
[15:31:26.109] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:49:0)
[15:31:26.109] Timer tick, asking Buildbucket for the build status
[15:31:26.195] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:26.195] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:50:0) after 1m0s
[15:32:26.265] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:50:0)
[15:32:26.265] Timer tick, asking Buildbucket for the build status
[15:32:26.321] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:26.321] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:51:0) after 1m0s
[15:33:26.374] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:51:0)
[15:33:26.374] Timer tick, asking Buildbucket for the build status
[15:33:26.444] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:26.444] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:52:0) after 1m0s
[15:34:26.618] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:52:0)
[15:34:26.618] Timer tick, asking Buildbucket for the build status
[15:34:26.751] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:26.751] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:53:0) after 1m0s
[15:35:26.834] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:53:0)
[15:35:26.834] Timer tick, asking Buildbucket for the build status
[15:35:27.065] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:27.065] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:54:0) after 1m0s
[15:36:27.159] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:54:0)
[15:36:27.159] Timer tick, asking Buildbucket for the build status
[15:36:27.260] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:27.260] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:55:0) after 1m0s
[15:37:27.312] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:55:0)
[15:37:27.312] Timer tick, asking Buildbucket for the build status
[15:37:27.377] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:27.377] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:56:0) after 1m0s
[15:38:27.478] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:56:0)
[15:38:27.478] Timer tick, asking Buildbucket for the build status
[15:38:27.617] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:27.617] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:57:0) after 1m0s
[15:39:27.610] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:57:0)
[15:39:27.610] Timer tick, asking Buildbucket for the build status
[15:39:27.886] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:27.886] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:58:0) after 1m0s
[15:40:27.955] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:58:0)
[15:40:27.955] Timer tick, asking Buildbucket for the build status
[15:40:28.632] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:28.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:59:0) after 1m0s
[15:41:28.864] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:59:0)
[15:41:28.864] Timer tick, asking Buildbucket for the build status
[15:41:28.934] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:28.934] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:60:0) after 1m0s
[15:42:29.127] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:60:0)
[15:42:29.127] Timer tick, asking Buildbucket for the build status
[15:42:29.318] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:29.318] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:61:0) after 1m0s
[15:43:29.341] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:61:0)
[15:43:29.341] Timer tick, asking Buildbucket for the build status
[15:43:29.399] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:29.399] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:62:0) after 1m0s
[15:44:29.465] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:62:0)
[15:44:29.465] Timer tick, asking Buildbucket for the build status
[15:44:29.581] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:29.581] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:63:0) after 1m0s
[15:45:29.784] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:63:0)
[15:45:29.784] Timer tick, asking Buildbucket for the build status
[15:45:29.881] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:29.881] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:64:0) after 1m0s
[15:46:29.948] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:64:0)
[15:46:29.948] Timer tick, asking Buildbucket for the build status
[15:46:30.047] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:30.047] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:65:0) after 1m0s
[15:47:30.146] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:65:0)
[15:47:30.146] Timer tick, asking Buildbucket for the build status
[15:47:30.210] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:30.210] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:66:0) after 1m0s
[15:48:30.233] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:66:0)
[15:48:30.233] Timer tick, asking Buildbucket for the build status
[15:48:30.295] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:30.295] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:67:0) after 1m0s
[15:49:30.318] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:67:0)
[15:49:30.318] Timer tick, asking Buildbucket for the build status
[15:49:30.439] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:30.439] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:68:0) after 1m0s
[15:50:30.464] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:68:0)
[15:50:30.465] Timer tick, asking Buildbucket for the build status
[15:50:30.538] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:30.538] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:69:0) after 1m0s
[15:51:30.570] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:69:0)
[15:51:30.570] Timer tick, asking Buildbucket for the build status
[15:51:30.632] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:30.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:70:0) after 1m0s
[15:52:30.661] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:70:0)
[15:52:30.661] Timer tick, asking Buildbucket for the build status
[15:52:30.784] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:30.784] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:71:0) after 1m0s
[15:53:30.808] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:71:0)
[15:53:30.808] Timer tick, asking Buildbucket for the build status
[15:53:31.059] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:31.059] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:72:0) after 1m0s
[15:54:31.116] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:72:0)
[15:54:31.116] Timer tick, asking Buildbucket for the build status
[15:54:31.246] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:31.246] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:73:0) after 1m0s
[15:55:31.345] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:73:0)
[15:55:31.345] Timer tick, asking Buildbucket for the build status
[15:55:31.632] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:31.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:74:0) after 1m0s
[15:56:31.655] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:74:0)
[15:56:31.655] Timer tick, asking Buildbucket for the build status
[15:56:31.802] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:31.802] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:75:0) after 1m0s
[15:57:31.849] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:75:0)
[15:57:31.849] Timer tick, asking Buildbucket for the build status
[15:57:31.939] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:31.939] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:76:0) after 1m0s
[15:58:31.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:76:0)
[15:58:31.983] Timer tick, asking Buildbucket for the build status
[15:58:32.198] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:32.198] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:77:0) after 1m0s
[15:59:32.305] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:77:0)
[15:59:32.305] Timer tick, asking Buildbucket for the build status
[15:59:32.420] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:32.420] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:78:0) after 1m0s
[16:00:32.467] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:78:0)
[16:00:32.467] Timer tick, asking Buildbucket for the build status
[16:00:32.636] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:32.636] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:79:0) after 1m0s
[16:01:32.661] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:79:0)
[16:01:32.661] Timer tick, asking Buildbucket for the build status
[16:01:32.757] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:32.757] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:80:0) after 1m0s
[16:02:32.845] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:80:0)
[16:02:32.845] Timer tick, asking Buildbucket for the build status
[16:02:33.028] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:33.028] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:81:0) after 1m0s
[16:03:33.069] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:81:0)
[16:03:33.069] Timer tick, asking Buildbucket for the build status
[16:03:33.139] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:33.139] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:82:0) after 1m0s
[16:04:33.168] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:82:0)
[16:04:33.168] Timer tick, asking Buildbucket for the build status
[16:04:33.260] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:33.260] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:83:0) after 1m0s
[16:05:33.341] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:83:0)
[16:05:33.341] Timer tick, asking Buildbucket for the build status
[16:05:33.414] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:33.414] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:84:0) after 1m0s
[16:06:33.483] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:84:0)
[16:06:33.483] Timer tick, asking Buildbucket for the build status
[16:06:33.549] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:33.549] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:85:0) after 1m0s
[16:07:33.625] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:85:0)
[16:07:33.625] Timer tick, asking Buildbucket for the build status
[16:07:33.702] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:33.702] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:86:0) after 1m0s
[16:08:33.839] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:86:0)
[16:08:33.839] Timer tick, asking Buildbucket for the build status
[16:08:33.891] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:33.891] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:87:0) after 1m0s
[16:09:33.923] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:87:0)
[16:09:33.923] Timer tick, asking Buildbucket for the build status
[16:09:34.014] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:34.014] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:88:0) after 1m0s
[16:10:34.119] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:88:0)
[16:10:34.119] Timer tick, asking Buildbucket for the build status
[16:10:34.193] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:34.193] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:89:0) after 1m0s
[16:11:34.221] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:89:0)
[16:11:34.221] Timer tick, asking Buildbucket for the build status
[16:11:34.305] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:34.305] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:90:0) after 1m0s
[16:12:34.339] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:90:0)
[16:12:34.339] Timer tick, asking Buildbucket for the build status
[16:12:34.399] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:34.399] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:91:0) after 1m0s
[16:13:34.391] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:91:0)
[16:13:34.391] Timer tick, asking Buildbucket for the build status
[16:13:34.470] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:34.470] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:92:0) after 1m0s
[16:14:34.492] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:92:0)
[16:14:34.492] Timer tick, asking Buildbucket for the build status
[16:14:34.589] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:34.589] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:93:0) after 1m0s
[16:15:34.840] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:93:0)
[16:15:34.840] Timer tick, asking Buildbucket for the build status
[16:15:34.997] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:34.997] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:94:0) after 1m0s
[16:16:34.991] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:94:0)
[16:16:34.991] Timer tick, asking Buildbucket for the build status
[16:16:35.073] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:35.074] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:95:0) after 1m0s
[16:17:35.123] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:95:0)
[16:17:35.123] Timer tick, asking Buildbucket for the build status
[16:17:35.200] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:35.200] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:96:0) after 1m0s
[16:18:35.370] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:96:0)
[16:18:35.370] Timer tick, asking Buildbucket for the build status
[16:18:35.515] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:35.515] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:97:0) after 1m0s
[16:19:35.562] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:97:0)
[16:19:35.562] Timer tick, asking Buildbucket for the build status
[16:19:35.697] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:35.697] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:98:0) after 1m0s
[16:20:35.819] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:98:0)
[16:20:35.819] Timer tick, asking Buildbucket for the build status
[16:20:35.878] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:35.878] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:99:0) after 1m0s
[16:21:35.955] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:99:0)
[16:21:35.955] Timer tick, asking Buildbucket for the build status
[16:21:36.026] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:36.026] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:100:0) after 1m0s
[16:22:36.056] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:100:0)
[16:22:36.056] Timer tick, asking Buildbucket for the build status
[16:22:36.121] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:36.121] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:101:0) after 1m0s
[16:23:36.189] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:101:0)
[16:23:36.189] Timer tick, asking Buildbucket for the build status
[16:23:36.428] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:36.428] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:102:0) after 1m0s
[16:24:36.470] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:102:0)
[16:24:36.470] Timer tick, asking Buildbucket for the build status
[16:24:36.572] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:36.573] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:103:0) after 1m0s
[16:25:36.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:103:0)
[16:25:36.613] Timer tick, asking Buildbucket for the build status
[16:25:36.743] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:36.743] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:104:0) after 1m0s
[16:26:36.767] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:104:0)
[16:26:36.767] Timer tick, asking Buildbucket for the build status
[16:26:37.390] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:37.390] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:105:0) after 1m0s
[16:27:37.413] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:105:0)
[16:27:37.413] Timer tick, asking Buildbucket for the build status
[16:27:37.536] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:37.536] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:106:0) after 1m0s
[16:28:37.558] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:106:0)
[16:28:37.558] Timer tick, asking Buildbucket for the build status
[16:28:37.845] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:37.845] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:107:0) after 1m0s
[16:29:37.916] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:107:0)
[16:29:37.916] Timer tick, asking Buildbucket for the build status
[16:29:38.213] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:38.213] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:108:0) after 1m0s
[16:30:38.244] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:108:0)
[16:30:38.244] Timer tick, asking Buildbucket for the build status
[16:30:38.300] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:38.300] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:109:0) after 1m0s
[16:31:38.319] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:109:0)
[16:31:38.319] Timer tick, asking Buildbucket for the build status
[16:31:38.390] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:38.390] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:110:0) after 1m0s
[16:32:38.392] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:110:0)
[16:32:38.392] Timer tick, asking Buildbucket for the build status
[16:32:38.622] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:38.622] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:111:0) after 1m0s
[16:33:38.630] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:111:0)
[16:33:38.630] Timer tick, asking Buildbucket for the build status
[16:33:38.794] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:38.794] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:112:0) after 1m0s
[16:34:38.866] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:112:0)
[16:34:38.866] Timer tick, asking Buildbucket for the build status
[16:34:38.972] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:38.972] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:113:0) after 1m0s
[16:35:38.999] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:113:0)
[16:35:38.999] Timer tick, asking Buildbucket for the build status
[16:35:39.153] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:39.153] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:114:0) after 1m0s
[16:36:39.177] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:114:0)
[16:36:39.177] Timer tick, asking Buildbucket for the build status
[16:36:39.414] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:39.414] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:115:0) after 1m0s
[16:37:39.459] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:115:0)
[16:37:39.460] Timer tick, asking Buildbucket for the build status
[16:37:39.610] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:39.610] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:116:0) after 1m0s
[16:38:39.680] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:116:0)
[16:38:39.680] Timer tick, asking Buildbucket for the build status
[16:38:39.766] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:39.766] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:117:0) after 1m0s
[16:39:39.786] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:117:0)
[16:39:39.786] Timer tick, asking Buildbucket for the build status
[16:39:40.036] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:40.036] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:118:0) after 1m0s
[16:40:40.082] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:118:0)
[16:40:40.082] Timer tick, asking Buildbucket for the build status
[16:40:40.284] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:40.284] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:119:0) after 1m0s
[16:41:40.315] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:119:0)
[16:41:40.315] Timer tick, asking Buildbucket for the build status
[16:41:40.384] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:40.384] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:120:0) after 1m0s
[16:42:40.412] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:120:0)
[16:42:40.412] Timer tick, asking Buildbucket for the build status
[16:42:40.532] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:40.532] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:121:0) after 1m0s
[16:43:40.557] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:121:0)
[16:43:40.557] Timer tick, asking Buildbucket for the build status
[16:43:40.632] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:40.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:122:0) after 1m0s
[16:44:40.662] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:122:0)
[16:44:40.662] Timer tick, asking Buildbucket for the build status
[16:44:40.782] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:40.782] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:123:0) after 1m0s
[16:45:40.836] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:123:0)
[16:45:40.836] Timer tick, asking Buildbucket for the build status
[16:45:41.117] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:41.117] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:124:0) after 1m0s
[16:46:41.209] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:124:0)
[16:46:41.209] Timer tick, asking Buildbucket for the build status
[16:46:41.359] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:41.359] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:125:0) after 1m0s
[16:47:41.394] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:125:0)
[16:47:41.394] Timer tick, asking Buildbucket for the build status
[16:47:41.457] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:41.457] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:126:0) after 1m0s
[16:48:41.534] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:126:0)
[16:48:41.534] Timer tick, asking Buildbucket for the build status
[16:48:41.597] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:41.597] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:127:0) after 1m0s
[16:49:41.686] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:127:0)
[16:49:41.686] Timer tick, asking Buildbucket for the build status
[16:49:41.882] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:41.882] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:128:0) after 1m0s
[16:50:41.907] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:128:0)
[16:50:41.907] Timer tick, asking Buildbucket for the build status
[16:50:41.970] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:41.970] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:129:0) after 1m0s
[16:51:42.035] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:129:0)
[16:51:42.035] Timer tick, asking Buildbucket for the build status
[16:51:42.417] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:42.417] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:130:0) after 1m0s
[16:52:42.461] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:130:0)
[16:52:42.461] Timer tick, asking Buildbucket for the build status
[16:52:42.540] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:42.540] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:131:0) after 1m0s
[16:53:42.622] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:131:0)
[16:53:42.622] Timer tick, asking Buildbucket for the build status
[16:53:42.687] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:42.687] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:132:0) after 1m0s
[16:54:42.723] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:132:0)
[16:54:42.723] Timer tick, asking Buildbucket for the build status
[16:54:43.004] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:43.004] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:133:0) after 1m0s
[16:55:43.066] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:133:0)
[16:55:43.066] Timer tick, asking Buildbucket for the build status
[16:55:43.190] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:43.190] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:134:0) after 1m0s
[16:56:43.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:134:0)
[16:56:43.263] Timer tick, asking Buildbucket for the build status
[16:56:43.326] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:43.326] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:135:0) after 1m0s
[16:57:43.327] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:135:0)
[16:57:43.327] Timer tick, asking Buildbucket for the build status
[16:57:43.582] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:43.582] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:136:0) after 1m0s
[16:58:43.609] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:136:0)
[16:58:43.609] Timer tick, asking Buildbucket for the build status
[16:58:43.779] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:43.779] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:137:0) after 1m0s
[16:59:43.870] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:137:0)
[16:59:43.870] Timer tick, asking Buildbucket for the build status
[16:59:43.952] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:43.952] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:138:0) after 1m0s
[17:00:43.986] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:138:0)
[17:00:43.986] Timer tick, asking Buildbucket for the build status
[17:00:44.059] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:44.059] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:139:0) after 1m0s
[17:01:44.089] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:139:0)
[17:01:44.089] Timer tick, asking Buildbucket for the build status
[17:01:44.174] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:44.174] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:140:0) after 1m0s
[17:02:45.296] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:140:0)
[17:02:45.296] Timer tick, asking Buildbucket for the build status
[17:02:45.411] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:45.411] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:141:0) after 1m0s
[17:03:45.436] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:141:0)
[17:03:45.436] Timer tick, asking Buildbucket for the build status
[17:03:45.613] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:45.613] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:142:0) after 1m0s
[17:04:45.668] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:142:0)
[17:04:45.668] Timer tick, asking Buildbucket for the build status
[17:04:46.008] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:46.008] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:143:0) after 1m0s
[17:05:46.068] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:143:0)
[17:05:46.068] Timer tick, asking Buildbucket for the build status
[17:05:46.128] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:46.128] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:144:0) after 1m0s
[17:06:46.206] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:144:0)
[17:06:46.206] Timer tick, asking Buildbucket for the build status
[17:06:47.836] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:47.836] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:145:0) after 1m0s
[17:07:47.867] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:145:0)
[17:07:47.867] Timer tick, asking Buildbucket for the build status
[17:07:47.937] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:47.937] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:146:0) after 1m0s
[17:08:48.034] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:146:0)
[17:08:48.034] Timer tick, asking Buildbucket for the build status
[17:08:48.105] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:48.105] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:147:0) after 1m0s
[17:09:48.094] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:147:0)
[17:09:48.094] Timer tick, asking Buildbucket for the build status
[17:09:48.342] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:48.342] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:148:0) after 1m0s
[17:10:48.368] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:148:0)
[17:10:48.368] Timer tick, asking Buildbucket for the build status
[17:10:48.429] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:48.429] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:149:0) after 1m0s
[17:11:48.542] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:149:0)
[17:11:48.542] Timer tick, asking Buildbucket for the build status
[17:11:48.599] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:48.599] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:150:0) after 1m0s
[17:12:48.660] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:150:0)
[17:12:48.660] Timer tick, asking Buildbucket for the build status
[17:12:48.731] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:48.731] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:151:0) after 1m0s
[17:13:48.815] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:151:0)
[17:13:48.815] Timer tick, asking Buildbucket for the build status
[17:13:48.879] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:48.879] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:152:0) after 1m0s
[17:14:48.965] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:152:0)
[17:14:48.965] Timer tick, asking Buildbucket for the build status
[17:14:51.051] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:51.051] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:153:0) after 1m0s
[17:15:51.116] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:153:0)
[17:15:51.117] Timer tick, asking Buildbucket for the build status
[17:15:51.253] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:51.253] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:154:0) after 1m0s
[17:16:51.280] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:154:0)
[17:16:51.280] Timer tick, asking Buildbucket for the build status
[17:16:51.348] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:51.348] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:155:0) after 1m0s
[17:17:51.418] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:155:0)
[17:17:51.418] Timer tick, asking Buildbucket for the build status
[17:17:51.519] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:51.519] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:156:0) after 1m0s
[17:18:51.545] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:156:0)
[17:18:51.545] Timer tick, asking Buildbucket for the build status
[17:18:51.740] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:51.740] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:157:0) after 1m0s
[17:19:51.806] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:157:0)
[17:19:51.806] Timer tick, asking Buildbucket for the build status
[17:19:51.917] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:51.917] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:158:0) after 1m0s
[17:20:51.952] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:158:0)
[17:20:51.952] Timer tick, asking Buildbucket for the build status
[17:20:52.086] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:52.086] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:159:0) after 1m0s
[17:21:52.106] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:159:0)
[17:21:52.106] Timer tick, asking Buildbucket for the build status
[17:21:52.161] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:52.161] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:160:0) after 1m0s
[17:22:52.186] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:160:0)
[17:22:52.186] Timer tick, asking Buildbucket for the build status
[17:22:52.284] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:52.285] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:161:0) after 1m0s
[17:23:52.315] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:161:0)
[17:23:52.315] Timer tick, asking Buildbucket for the build status
[17:23:52.406] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:52.406] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:162:0) after 1m0s
[17:24:52.453] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:162:0)
[17:24:52.453] Timer tick, asking Buildbucket for the build status
[17:24:52.545] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:52.545] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:163:0) after 1m0s
[17:25:52.581] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:163:0)
[17:25:52.581] Timer tick, asking Buildbucket for the build status
[17:25:52.642] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:52.642] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:164:0) after 1m0s
[17:26:52.690] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:164:0)
[17:26:52.690] Timer tick, asking Buildbucket for the build status
[17:26:52.755] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:52.755] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:165:0) after 1m0s
[17:27:52.852] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:165:0)
[17:27:52.852] Timer tick, asking Buildbucket for the build status
[17:27:52.932] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:52.932] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:166:0) after 1m0s
[17:28:53.021] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:166:0)
[17:28:53.021] Timer tick, asking Buildbucket for the build status
[17:28:53.080] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:53.080] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:167:0) after 1m0s
[17:29:53.127] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:167:0)
[17:29:53.127] Timer tick, asking Buildbucket for the build status
[17:29:53.216] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:53.216] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:168:0) after 1m0s
[17:30:53.331] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:168:0)
[17:30:53.331] Timer tick, asking Buildbucket for the build status
[17:30:53.408] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:53.408] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:169:0) after 1m0s
[17:31:53.469] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:169:0)
[17:31:53.469] Timer tick, asking Buildbucket for the build status
[17:31:53.534] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:53.534] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:170:0) after 1m0s
[17:32:53.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:170:0)
[17:32:53.613] Timer tick, asking Buildbucket for the build status
[17:32:53.667] Build 8860566151056462976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:53.667] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025996213104720064:171:0) after 1m0s
[17:33:10.018] Received PubSub notification, asking Buildbucket for the build status
[17:33:10.156] Build 8860566151056462976: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:33:10.156] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608312789192132",
"created_by": "project:chromium",
"created_ts": "1608302655126726",
"id": "8860566151056462976",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"47cf2e55df353230cd866d8971b4f9526513bd61\"}}",
"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\": \"47cf2e55df353230cd866d8971b4f9526513bd61\", \"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-04x7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1135, \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"64c5d601f89b7733091222796ff3631668fb2c25\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"47cf2e55df353230cd866d8971b4f9526513bd61\", \"got_revision_cp\": \"refs/heads/master@{#838456}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"f841b934b402f97c1b88f08c8bcb6c0dac13f0d3\", \"got_v8_revision_cp\": \"refs/heads/8.9.175@{#1}\", \"got_webrtc_revision\": \"d7f750f7be4b2f61018d3cb55a995d09daa226e6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32862}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"47cf2e55df353230cd866d8971b4f9526513bd61\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f375b1f56d48f8eb1b78ff74f62ac76927591c747ddd46d6194a7cde2679a0e3_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-1-04x7\"], \"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\": [\"5657-0417a3f\"], \"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": "1608302683838614",
"status": "COMPLETED",
"status_changed_ts": "1608312789645583",
"tags": [
"build_address:luci.chromium.ci/win32-official/1135",
"builder:win32-official",
"buildset:commit/git/47cf2e55df353230cd866d8971b4f9526513bd61",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/47cf2e55df353230cd866d8971b4f9526513bd61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025996213104720064",
"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/8860566151056462976/+/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:508f2080f1a45510",
"user_agent:luci-scheduler"
],
"updated_ts": "1608312789690258",
"url": "https://ci.chromium.org/b/8860566151056462976",
"utcnow_ts": "1608312790146434"
}
}
[17:33:10.156] Invocation finished in 2h48m56.367084457s with status SUCCEEDED