[13:27:45.152] New invocation is queued and will start shortly
[13:27:46.347] Starting the invocation (attempt 1)
[13:27:46.433] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:27:46.435] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:27:46.436] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021742967235713248",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9021742967235713248",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0",
"gitiles_ref:refs/heads/master"
]
}
[13:27:47.118] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612358866599130",
"id": "8856312905055213584",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612358866931203",
"tags": [
"build_address:luci.chromium.ci/win-official/1527",
"builder:win-official",
"buildset:commit/git/8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021742967235713248",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856312905055213584/+/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": "1612358866931316",
"url": "https://ci.chromium.org/b/8856312905055213584",
"utcnow_ts": "1612358867106991"
}
}
[13:27:47.118] Task URL: https://ci.chromium.org/b/8856312905055213584
[13:27:47.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:2:0) after 1m0s
[13:28:47.149] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:2:0)
[13:28:47.149] Timer tick, asking Buildbucket for the build status
[13:28:47.573] Build 8856312905055213584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:28:47.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:3:0) after 1m0s
[13:29:47.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:3:0)
[13:29:47.650] Timer tick, asking Buildbucket for the build status
[13:29:47.731] Build 8856312905055213584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:29:47.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:4:0) after 1m0s
[13:30:47.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:4:0)
[13:30:47.868] Timer tick, asking Buildbucket for the build status
[13:30:47.994] Build 8856312905055213584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:30:47.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:5:0) after 1m0s
[13:31:48.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:5:0)
[13:31:48.101] Timer tick, asking Buildbucket for the build status
[13:31:48.242] Build 8856312905055213584: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:48.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:6:0) after 1m0s
[13:31:55.081] Received PubSub notification, asking Buildbucket for the build status
[13:31:55.152] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:48.381] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:6:0)
[13:32:48.381] Timer tick, asking Buildbucket for the build status
[13:32:48.580] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:48.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:8:0) after 1m0s
[13:33:48.586] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:8:0)
[13:33:48.586] Timer tick, asking Buildbucket for the build status
[13:33:48.732] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:48.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:9:0) after 1m0s
[13:34:49.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:9:0)
[13:34:49.369] Timer tick, asking Buildbucket for the build status
[13:34:49.449] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:49.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:10:0) after 1m0s
[13:35:49.582] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:10:0)
[13:35:49.582] Timer tick, asking Buildbucket for the build status
[13:35:49.664] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:49.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:11:0) after 1m0s
[13:36:49.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:11:0)
[13:36:49.760] Timer tick, asking Buildbucket for the build status
[13:36:49.921] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:49.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:12:0) after 1m0s
[13:37:50.028] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:12:0)
[13:37:50.028] Timer tick, asking Buildbucket for the build status
[13:37:50.751] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:50.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:13:0) after 1m0s
[13:38:50.853] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:13:0)
[13:38:50.853] Timer tick, asking Buildbucket for the build status
[13:38:51.489] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:51.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:14:0) after 1m0s
[13:39:51.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:14:0)
[13:39:51.533] Timer tick, asking Buildbucket for the build status
[13:39:51.630] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:51.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:15:0) after 1m0s
[13:40:51.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:15:0)
[13:40:51.662] Timer tick, asking Buildbucket for the build status
[13:40:51.730] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:51.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:16:0) after 1m0s
[13:41:52.344] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:16:0)
[13:41:52.344] Timer tick, asking Buildbucket for the build status
[13:41:52.712] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:52.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:17:0) after 1m0s
[13:42:52.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:17:0)
[13:42:52.819] Timer tick, asking Buildbucket for the build status
[13:42:52.936] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:52.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:18:0) after 1m0s
[13:43:53.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:18:0)
[13:43:53.160] Timer tick, asking Buildbucket for the build status
[13:43:53.257] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:53.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:19:0) after 1m0s
[13:44:53.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:19:0)
[13:44:53.327] Timer tick, asking Buildbucket for the build status
[13:44:53.532] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:53.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:20:0) after 1m0s
[13:45:53.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:20:0)
[13:45:53.717] Timer tick, asking Buildbucket for the build status
[13:45:53.809] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:53.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:21:0) after 1m0s
[13:46:53.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:21:0)
[13:46:53.834] Timer tick, asking Buildbucket for the build status
[13:46:53.906] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:53.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:22:0) after 1m0s
[13:47:53.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:22:0)
[13:47:53.981] Timer tick, asking Buildbucket for the build status
[13:47:54.101] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:54.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:23:0) after 1m0s
[13:48:54.224] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:23:0)
[13:48:54.224] Timer tick, asking Buildbucket for the build status
[13:48:54.303] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:54.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:24:0) after 1m0s
[13:49:54.558] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:24:0)
[13:49:54.558] Timer tick, asking Buildbucket for the build status
[13:49:54.639] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:54.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:25:0) after 1m0s
[13:50:54.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:25:0)
[13:50:54.887] Timer tick, asking Buildbucket for the build status
[13:50:54.974] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:54.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:26:0) after 1m0s
[13:51:55.032] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:26:0)
[13:51:55.032] Timer tick, asking Buildbucket for the build status
[13:51:55.401] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:55.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:27:0) after 1m0s
[13:52:55.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:27:0)
[13:52:55.552] Timer tick, asking Buildbucket for the build status
[13:52:55.661] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:55.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:28:0) after 1m0s
[13:53:55.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:28:0)
[13:53:55.699] Timer tick, asking Buildbucket for the build status
[13:53:55.901] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:55.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:29:0) after 1m0s
[13:54:55.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:29:0)
[13:54:55.957] Timer tick, asking Buildbucket for the build status
[13:54:56.091] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:56.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:30:0) after 1m0s
[13:55:56.181] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:30:0)
[13:55:56.181] Timer tick, asking Buildbucket for the build status
[13:55:56.378] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:56.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:31:0) after 1m0s
[13:56:56.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:31:0)
[13:56:56.484] Timer tick, asking Buildbucket for the build status
[13:56:56.601] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:56.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:32:0) after 1m0s
[13:57:56.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:32:0)
[13:57:56.614] Timer tick, asking Buildbucket for the build status
[13:57:57.087] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:57.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:33:0) after 1m0s
[13:58:57.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:33:0)
[13:58:57.151] Timer tick, asking Buildbucket for the build status
[13:58:57.219] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:57.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:34:0) after 1m0s
[13:59:57.276] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:34:0)
[13:59:57.276] Timer tick, asking Buildbucket for the build status
[13:59:57.348] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:57.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:35:0) after 1m0s
[14:00:57.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:35:0)
[14:00:57.434] Timer tick, asking Buildbucket for the build status
[14:00:57.605] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:57.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:36:0) after 1m0s
[14:01:57.707] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:36:0)
[14:01:57.707] Timer tick, asking Buildbucket for the build status
[14:01:57.895] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:57.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:37:0) after 1m0s
[14:02:58.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:37:0)
[14:02:58.328] Timer tick, asking Buildbucket for the build status
[14:02:58.476] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:58.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:38:0) after 1m0s
[14:03:58.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:38:0)
[14:03:58.777] Timer tick, asking Buildbucket for the build status
[14:03:58.878] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:58.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:39:0) after 1m0s
[14:04:59.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:39:0)
[14:04:59.264] Timer tick, asking Buildbucket for the build status
[14:04:59.398] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:59.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:40:0) after 1m0s
[14:05:59.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:40:0)
[14:05:59.460] Timer tick, asking Buildbucket for the build status
[14:05:59.525] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:59.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:41:0) after 1m0s
[14:06:59.840] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:41:0)
[14:06:59.840] Timer tick, asking Buildbucket for the build status
[14:06:59.958] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:59.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:42:0) after 1m0s
[14:08:00.055] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:42:0)
[14:08:00.055] Timer tick, asking Buildbucket for the build status
[14:08:00.208] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:00.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:43:0) after 1m0s
[14:09:00.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:43:0)
[14:09:00.298] Timer tick, asking Buildbucket for the build status
[14:09:00.434] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:00.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:44:0) after 1m0s
[14:10:00.445] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:44:0)
[14:10:00.445] Timer tick, asking Buildbucket for the build status
[14:10:00.500] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:00.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:45:0) after 1m0s
[14:11:00.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:45:0)
[14:11:00.607] Timer tick, asking Buildbucket for the build status
[14:11:00.707] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:00.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:46:0) after 1m0s
[14:12:00.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:46:0)
[14:12:00.887] Timer tick, asking Buildbucket for the build status
[14:12:01.150] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:01.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:47:0) after 1m0s
[14:13:01.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:47:0)
[14:13:01.345] Timer tick, asking Buildbucket for the build status
[14:13:01.453] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:01.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:48:0) after 1m0s
[14:14:01.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:48:0)
[14:14:01.451] Timer tick, asking Buildbucket for the build status
[14:14:01.632] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:01.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:49:0) after 1m0s
[14:15:01.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:49:0)
[14:15:01.738] Timer tick, asking Buildbucket for the build status
[14:15:02.192] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:02.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:50:0) after 1m0s
[14:16:02.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:50:0)
[14:16:02.245] Timer tick, asking Buildbucket for the build status
[14:16:02.412] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:02.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:51:0) after 1m0s
[14:17:02.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:51:0)
[14:17:02.458] Timer tick, asking Buildbucket for the build status
[14:17:02.550] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:02.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:52:0) after 1m0s
[14:18:02.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:52:0)
[14:18:02.720] Timer tick, asking Buildbucket for the build status
[14:18:02.817] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:02.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:53:0) after 1m0s
[14:19:02.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:53:0)
[14:19:02.913] Timer tick, asking Buildbucket for the build status
[14:19:03.063] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:03.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:54:0) after 1m0s
[14:20:03.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:54:0)
[14:20:03.171] Timer tick, asking Buildbucket for the build status
[14:20:03.395] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:03.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:55:0) after 1m0s
[14:21:03.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:55:0)
[14:21:03.485] Timer tick, asking Buildbucket for the build status
[14:21:03.573] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:03.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:56:0) after 1m0s
[14:22:03.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:56:0)
[14:22:03.732] Timer tick, asking Buildbucket for the build status
[14:22:03.937] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:03.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:57:0) after 1m0s
[14:23:03.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:57:0)
[14:23:03.965] Timer tick, asking Buildbucket for the build status
[14:23:04.078] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:04.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:58:0) after 1m0s
[14:24:04.143] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:58:0)
[14:24:04.144] Timer tick, asking Buildbucket for the build status
[14:24:04.228] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:04.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:59:0) after 1m0s
[14:25:04.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:59:0)
[14:25:04.260] Timer tick, asking Buildbucket for the build status
[14:25:04.357] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:04.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:60:0) after 1m0s
[14:26:04.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:60:0)
[14:26:04.400] Timer tick, asking Buildbucket for the build status
[14:26:04.461] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:04.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:61:0) after 1m0s
[14:27:04.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:61:0)
[14:27:04.507] Timer tick, asking Buildbucket for the build status
[14:27:04.581] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:04.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:62:0) after 1m0s
[14:28:04.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:62:0)
[14:28:04.647] Timer tick, asking Buildbucket for the build status
[14:28:04.875] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:04.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:63:0) after 1m0s
[14:29:04.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:63:0)
[14:29:04.898] Timer tick, asking Buildbucket for the build status
[14:29:04.998] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:04.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:64:0) after 1m0s
[14:30:05.046] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:64:0)
[14:30:05.046] Timer tick, asking Buildbucket for the build status
[14:30:05.117] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:05.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:65:0) after 1m0s
[14:31:05.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:65:0)
[14:31:05.171] Timer tick, asking Buildbucket for the build status
[14:31:05.268] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:05.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:66:0) after 1m0s
[14:32:05.564] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:66:0)
[14:32:05.564] Timer tick, asking Buildbucket for the build status
[14:32:05.635] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:05.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:67:0) after 1m0s
[14:33:05.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:67:0)
[14:33:05.861] Timer tick, asking Buildbucket for the build status
[14:33:06.553] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:06.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:68:0) after 1m0s
[14:34:06.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:68:0)
[14:34:06.667] Timer tick, asking Buildbucket for the build status
[14:34:06.841] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:06.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:69:0) after 1m0s
[14:35:06.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:69:0)
[14:35:06.921] Timer tick, asking Buildbucket for the build status
[14:35:06.995] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:06.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:70:0) after 1m0s
[14:36:07.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:70:0)
[14:36:07.270] Timer tick, asking Buildbucket for the build status
[14:36:07.493] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:07.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:71:0) after 1m0s
[14:37:07.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:71:0)
[14:37:07.673] Timer tick, asking Buildbucket for the build status
[14:37:07.733] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:07.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:72:0) after 1m0s
[14:38:07.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:72:0)
[14:38:07.787] Timer tick, asking Buildbucket for the build status
[14:38:08.254] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:08.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:73:0) after 1m0s
[14:39:08.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:73:0)
[14:39:08.307] Timer tick, asking Buildbucket for the build status
[14:39:08.443] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:08.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:74:0) after 1m0s
[14:40:08.497] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:74:0)
[14:40:08.497] Timer tick, asking Buildbucket for the build status
[14:40:08.580] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:08.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:75:0) after 1m0s
[14:41:08.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:75:0)
[14:41:08.676] Timer tick, asking Buildbucket for the build status
[14:41:08.850] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:08.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:76:0) after 1m0s
[14:42:09.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:76:0)
[14:42:09.066] Timer tick, asking Buildbucket for the build status
[14:42:09.156] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:09.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:77:0) after 1m0s
[14:43:09.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:77:0)
[14:43:09.318] Timer tick, asking Buildbucket for the build status
[14:43:09.504] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:09.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:78:0) after 1m0s
[14:44:09.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:78:0)
[14:44:09.639] Timer tick, asking Buildbucket for the build status
[14:44:09.793] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:09.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:79:0) after 1m0s
[14:45:09.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:79:0)
[14:45:09.873] Timer tick, asking Buildbucket for the build status
[14:45:09.936] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:09.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:80:0) after 1m0s
[14:46:10.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:80:0)
[14:46:10.038] Timer tick, asking Buildbucket for the build status
[14:46:10.102] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:10.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:81:0) after 1m0s
[14:47:10.203] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:81:0)
[14:47:10.204] Timer tick, asking Buildbucket for the build status
[14:47:10.263] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:10.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:82:0) after 1m0s
[14:48:10.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:82:0)
[14:48:10.348] Timer tick, asking Buildbucket for the build status
[14:48:10.522] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:10.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:83:0) after 1m0s
[14:49:10.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:83:0)
[14:49:10.556] Timer tick, asking Buildbucket for the build status
[14:49:10.618] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:10.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:84:0) after 1m0s
[14:50:10.705] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:84:0)
[14:50:10.705] Timer tick, asking Buildbucket for the build status
[14:50:10.757] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:10.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:85:0) after 1m0s
[14:51:10.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:85:0)
[14:51:10.818] Timer tick, asking Buildbucket for the build status
[14:51:10.883] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:10.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:86:0) after 1m0s
[14:52:10.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:86:0)
[14:52:10.999] Timer tick, asking Buildbucket for the build status
[14:52:11.064] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:11.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:87:0) after 1m0s
[14:53:11.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:87:0)
[14:53:11.104] Timer tick, asking Buildbucket for the build status
[14:53:11.508] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:11.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:88:0) after 1m0s
[14:54:11.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:88:0)
[14:54:11.554] Timer tick, asking Buildbucket for the build status
[14:54:11.630] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:11.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:89:0) after 1m0s
[14:55:11.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:89:0)
[14:55:11.713] Timer tick, asking Buildbucket for the build status
[14:55:12.115] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:12.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:90:0) after 1m0s
[14:56:12.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:90:0)
[14:56:12.199] Timer tick, asking Buildbucket for the build status
[14:56:12.593] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:12.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:91:0) after 1m0s
[14:57:12.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:91:0)
[14:57:12.708] Timer tick, asking Buildbucket for the build status
[14:57:12.771] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:12.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:92:0) after 1m0s
[14:58:12.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:92:0)
[14:58:12.794] Timer tick, asking Buildbucket for the build status
[14:58:13.098] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:13.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:93:0) after 1m0s
[14:59:13.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:93:0)
[14:59:13.131] Timer tick, asking Buildbucket for the build status
[14:59:13.317] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:13.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:94:0) after 1m0s
[15:00:13.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:94:0)
[15:00:13.439] Timer tick, asking Buildbucket for the build status
[15:00:13.558] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:13.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:95:0) after 1m0s
[15:01:13.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:95:0)
[15:01:13.615] Timer tick, asking Buildbucket for the build status
[15:01:13.943] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:13.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:96:0) after 1m0s
[15:02:14.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:96:0)
[15:02:14.043] Timer tick, asking Buildbucket for the build status
[15:02:14.132] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:14.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:97:0) after 1m0s
[15:03:14.255] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:97:0)
[15:03:14.255] Timer tick, asking Buildbucket for the build status
[15:03:14.364] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:14.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:98:0) after 1m0s
[15:04:14.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:98:0)
[15:04:14.401] Timer tick, asking Buildbucket for the build status
[15:04:14.729] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:14.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:99:0) after 1m0s
[15:05:14.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:99:0)
[15:05:14.803] Timer tick, asking Buildbucket for the build status
[15:05:14.910] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:14.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:100:0) after 1m0s
[15:06:14.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:100:0)
[15:06:14.973] Timer tick, asking Buildbucket for the build status
[15:06:15.048] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:15.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:101:0) after 1m0s
[15:07:15.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:101:0)
[15:07:15.116] Timer tick, asking Buildbucket for the build status
[15:07:15.184] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:15.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:102:0) after 1m0s
[15:08:15.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:102:0)
[15:08:15.260] Timer tick, asking Buildbucket for the build status
[15:08:15.367] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:15.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:103:0) after 1m0s
[15:09:15.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:103:0)
[15:09:15.414] Timer tick, asking Buildbucket for the build status
[15:09:15.485] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:15.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:104:0) after 1m0s
[15:10:15.582] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:104:0)
[15:10:15.582] Timer tick, asking Buildbucket for the build status
[15:10:15.753] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:15.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:105:0) after 1m0s
[15:11:15.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:105:0)
[15:11:15.734] Timer tick, asking Buildbucket for the build status
[15:11:15.794] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:15.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:106:0) after 1m0s
[15:12:15.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:106:0)
[15:12:15.847] Timer tick, asking Buildbucket for the build status
[15:12:16.282] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:16.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:107:0) after 1m0s
[15:13:16.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:107:0)
[15:13:16.267] Timer tick, asking Buildbucket for the build status
[15:13:16.375] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:16.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:108:0) after 1m0s
[15:14:16.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:108:0)
[15:14:16.422] Timer tick, asking Buildbucket for the build status
[15:14:16.511] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:16.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:109:0) after 1m0s
[15:15:16.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:109:0)
[15:15:16.573] Timer tick, asking Buildbucket for the build status
[15:15:16.709] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:16.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:110:0) after 1m0s
[15:16:16.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:110:0)
[15:16:16.775] Timer tick, asking Buildbucket for the build status
[15:16:17.789] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:17.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:111:0) after 1m0s
[15:17:17.901] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:111:0)
[15:17:17.901] Timer tick, asking Buildbucket for the build status
[15:17:17.985] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:17.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:112:0) after 1m0s
[15:18:18.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:112:0)
[15:18:18.096] Timer tick, asking Buildbucket for the build status
[15:18:18.531] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:18.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:113:0) after 1m0s
[15:19:18.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:113:0)
[15:19:18.609] Timer tick, asking Buildbucket for the build status
[15:19:18.666] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:18.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:114:0) after 1m0s
[15:20:18.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:114:0)
[15:20:18.754] Timer tick, asking Buildbucket for the build status
[15:20:18.846] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:18.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:115:0) after 1m0s
[15:21:18.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:115:0)
[15:21:18.891] Timer tick, asking Buildbucket for the build status
[15:21:19.338] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:19.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:116:0) after 1m0s
[15:22:19.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:116:0)
[15:22:19.392] Timer tick, asking Buildbucket for the build status
[15:22:19.625] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:19.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:117:0) after 1m0s
[15:23:19.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:117:0)
[15:23:19.653] Timer tick, asking Buildbucket for the build status
[15:23:19.755] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:19.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:118:0) after 1m0s
[15:24:19.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:118:0)
[15:24:19.783] Timer tick, asking Buildbucket for the build status
[15:24:19.945] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:19.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:119:0) after 1m0s
[15:25:20.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:119:0)
[15:25:20.006] Timer tick, asking Buildbucket for the build status
[15:25:20.172] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:20.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:120:0) after 1m0s
[15:26:20.200] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:120:0)
[15:26:20.200] Timer tick, asking Buildbucket for the build status
[15:26:20.520] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:20.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:121:0) after 1m0s
[15:27:20.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:121:0)
[15:27:20.573] Timer tick, asking Buildbucket for the build status
[15:27:20.826] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:20.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:122:0) after 1m0s
[15:28:20.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:122:0)
[15:28:20.911] Timer tick, asking Buildbucket for the build status
[15:28:21.286] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:21.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:123:0) after 1m0s
[15:29:21.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:123:0)
[15:29:21.414] Timer tick, asking Buildbucket for the build status
[15:29:21.470] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:21.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:124:0) after 1m0s
[15:30:21.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:124:0)
[15:30:21.485] Timer tick, asking Buildbucket for the build status
[15:30:21.847] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:21.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:125:0) after 1m0s
[15:31:21.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:125:0)
[15:31:21.863] Timer tick, asking Buildbucket for the build status
[15:31:22.497] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:22.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:126:0) after 1m0s
[15:32:22.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:126:0)
[15:32:22.514] Timer tick, asking Buildbucket for the build status
[15:32:22.573] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:22.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:127:0) after 1m0s
[15:33:22.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:127:0)
[15:33:22.717] Timer tick, asking Buildbucket for the build status
[15:33:22.844] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:22.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:128:0) after 1m0s
[15:34:22.932] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:128:0)
[15:34:22.932] Timer tick, asking Buildbucket for the build status
[15:34:23.037] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:23.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:129:0) after 1m0s
[15:35:23.175] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:129:0)
[15:35:23.175] Timer tick, asking Buildbucket for the build status
[15:35:23.256] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:23.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:130:0) after 1m0s
[15:36:23.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:130:0)
[15:36:23.422] Timer tick, asking Buildbucket for the build status
[15:36:25.676] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:25.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:131:0) after 1m0s
[15:37:25.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:131:0)
[15:37:25.764] Timer tick, asking Buildbucket for the build status
[15:37:26.268] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:26.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:132:0) after 1m0s
[15:38:26.301] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:132:0)
[15:38:26.301] Timer tick, asking Buildbucket for the build status
[15:38:26.411] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:26.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:133:0) after 1m0s
[15:39:26.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:133:0)
[15:39:26.535] Timer tick, asking Buildbucket for the build status
[15:39:27.219] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:27.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:134:0) after 1m0s
[15:40:27.424] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:134:0)
[15:40:27.424] Timer tick, asking Buildbucket for the build status
[15:40:27.935] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:27.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:135:0) after 1m0s
[15:41:28.181] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:135:0)
[15:41:28.181] Timer tick, asking Buildbucket for the build status
[15:41:28.252] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:28.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:136:0) after 1m0s
[15:42:28.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:136:0)
[15:42:28.335] Timer tick, asking Buildbucket for the build status
[15:42:28.400] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:28.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:137:0) after 1m0s
[15:43:28.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:137:0)
[15:43:28.516] Timer tick, asking Buildbucket for the build status
[15:43:28.746] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:28.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:138:0) after 1m0s
[15:44:28.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:138:0)
[15:44:28.815] Timer tick, asking Buildbucket for the build status
[15:44:29.109] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:29.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:139:0) after 1m0s
[15:45:29.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:139:0)
[15:45:29.214] Timer tick, asking Buildbucket for the build status
[15:45:29.290] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:29.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:140:0) after 1m0s
[15:46:29.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:140:0)
[15:46:29.342] Timer tick, asking Buildbucket for the build status
[15:46:29.508] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:29.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:141:0) after 1m0s
[15:47:29.559] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:141:0)
[15:47:29.559] Timer tick, asking Buildbucket for the build status
[15:47:29.644] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:29.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:142:0) after 1m0s
[15:48:29.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:142:0)
[15:48:29.713] Timer tick, asking Buildbucket for the build status
[15:48:30.344] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:30.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:143:0) after 1m0s
[15:49:30.473] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:143:0)
[15:49:30.473] Timer tick, asking Buildbucket for the build status
[15:49:30.704] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:30.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:144:0) after 1m0s
[15:50:30.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:144:0)
[15:50:30.760] Timer tick, asking Buildbucket for the build status
[15:50:30.837] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:30.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:145:0) after 1m0s
[15:51:30.895] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:145:0)
[15:51:30.895] Timer tick, asking Buildbucket for the build status
[15:51:30.966] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:30.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:146:0) after 1m0s
[15:52:31.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:146:0)
[15:52:31.076] Timer tick, asking Buildbucket for the build status
[15:52:31.186] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:31.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:147:0) after 1m0s
[15:53:31.248] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:147:0)
[15:53:31.248] Timer tick, asking Buildbucket for the build status
[15:53:31.344] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:31.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:148:0) after 1m0s
[15:54:31.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:148:0)
[15:54:31.387] Timer tick, asking Buildbucket for the build status
[15:54:31.513] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:31.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:149:0) after 1m0s
[15:55:31.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:149:0)
[15:55:31.585] Timer tick, asking Buildbucket for the build status
[15:55:31.826] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:31.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:150:0) after 1m0s
[15:56:31.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:150:0)
[15:56:31.851] Timer tick, asking Buildbucket for the build status
[15:56:31.918] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:31.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:151:0) after 1m0s
[15:57:31.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:151:0)
[15:57:31.925] Timer tick, asking Buildbucket for the build status
[15:57:32.318] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:32.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:152:0) after 1m0s
[15:58:32.341] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:152:0)
[15:58:32.341] Timer tick, asking Buildbucket for the build status
[15:58:32.704] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:32.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:153:0) after 1m0s
[15:59:32.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:153:0)
[15:59:32.888] Timer tick, asking Buildbucket for the build status
[15:59:32.970] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:32.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:154:0) after 1m0s
[16:00:33.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:154:0)
[16:00:33.644] Timer tick, asking Buildbucket for the build status
[16:00:33.829] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:33.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:155:0) after 1m0s
[16:01:34.099] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:155:0)
[16:01:34.099] Timer tick, asking Buildbucket for the build status
[16:01:34.162] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:34.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:156:0) after 1m0s
[16:02:34.480] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:156:0)
[16:02:34.480] Timer tick, asking Buildbucket for the build status
[16:02:34.561] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:34.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:157:0) after 1m0s
[16:03:34.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:157:0)
[16:03:34.672] Timer tick, asking Buildbucket for the build status
[16:03:34.946] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:34.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:158:0) after 1m0s
[16:04:35.007] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:158:0)
[16:04:35.007] Timer tick, asking Buildbucket for the build status
[16:04:35.087] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:35.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:159:0) after 1m0s
[16:05:35.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:159:0)
[16:05:35.241] Timer tick, asking Buildbucket for the build status
[16:05:35.305] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:35.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:160:0) after 1m0s
[16:06:35.559] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:160:0)
[16:06:35.559] Timer tick, asking Buildbucket for the build status
[16:06:35.613] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:35.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:161:0) after 1m0s
[16:07:35.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:161:0)
[16:07:35.847] Timer tick, asking Buildbucket for the build status
[16:07:35.906] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:35.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:162:0) after 1m0s
[16:08:36.121] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:162:0)
[16:08:36.121] Timer tick, asking Buildbucket for the build status
[16:08:36.235] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:36.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:163:0) after 1m0s
[16:09:36.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:163:0)
[16:09:36.544] Timer tick, asking Buildbucket for the build status
[16:09:36.635] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:36.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:164:0) after 1m0s
[16:10:36.670] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:164:0)
[16:10:36.670] Timer tick, asking Buildbucket for the build status
[16:10:36.913] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:36.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:165:0) after 1m0s
[16:11:36.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:165:0)
[16:11:36.955] Timer tick, asking Buildbucket for the build status
[16:11:37.005] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:37.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:166:0) after 1m0s
[16:12:37.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:166:0)
[16:12:37.123] Timer tick, asking Buildbucket for the build status
[16:12:37.183] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:37.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:167:0) after 1m0s
[16:13:37.216] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:167:0)
[16:13:37.216] Timer tick, asking Buildbucket for the build status
[16:13:37.331] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:37.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:168:0) after 1m0s
[16:14:37.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:168:0)
[16:14:37.529] Timer tick, asking Buildbucket for the build status
[16:14:37.587] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:37.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:169:0) after 1m0s
[16:15:37.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:169:0)
[16:15:37.727] Timer tick, asking Buildbucket for the build status
[16:15:37.933] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:37.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:170:0) after 1m0s
[16:16:38.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:170:0)
[16:16:38.126] Timer tick, asking Buildbucket for the build status
[16:16:38.189] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:38.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:171:0) after 1m0s
[16:17:38.376] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:171:0)
[16:17:38.376] Timer tick, asking Buildbucket for the build status
[16:17:38.828] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:38.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:172:0) after 1m0s
[16:18:38.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:172:0)
[16:18:38.919] Timer tick, asking Buildbucket for the build status
[16:18:39.047] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:39.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:173:0) after 1m0s
[16:19:39.137] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:173:0)
[16:19:39.137] Timer tick, asking Buildbucket for the build status
[16:19:39.199] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:39.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:174:0) after 1m0s
[16:20:39.276] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:174:0)
[16:20:39.276] Timer tick, asking Buildbucket for the build status
[16:20:39.423] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:39.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:175:0) after 1m0s
[16:21:39.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:175:0)
[16:21:39.451] Timer tick, asking Buildbucket for the build status
[16:21:39.517] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:39.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:176:0) after 1m0s
[16:22:39.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:176:0)
[16:22:39.623] Timer tick, asking Buildbucket for the build status
[16:22:39.731] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:39.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:177:0) after 1m0s
[16:23:39.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:177:0)
[16:23:39.818] Timer tick, asking Buildbucket for the build status
[16:23:39.938] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:39.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:178:0) after 1m0s
[16:24:39.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:178:0)
[16:24:39.978] Timer tick, asking Buildbucket for the build status
[16:24:40.066] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:40.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:179:0) after 1m0s
[16:25:40.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:179:0)
[16:25:40.112] Timer tick, asking Buildbucket for the build status
[16:25:40.189] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:40.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:180:0) after 1m0s
[16:26:40.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:180:0)
[16:26:40.209] Timer tick, asking Buildbucket for the build status
[16:26:40.285] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:40.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:181:0) after 1m0s
[16:27:40.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:181:0)
[16:27:40.389] Timer tick, asking Buildbucket for the build status
[16:27:40.621] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:40.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:182:0) after 1m0s
[16:28:40.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:182:0)
[16:28:40.726] Timer tick, asking Buildbucket for the build status
[16:28:41.052] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:41.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:183:0) after 1m0s
[16:29:41.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:183:0)
[16:29:41.146] Timer tick, asking Buildbucket for the build status
[16:29:41.378] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:41.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:184:0) after 1m0s
[16:30:41.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:184:0)
[16:30:41.388] Timer tick, asking Buildbucket for the build status
[16:30:41.651] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:41.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:185:0) after 1m0s
[16:31:41.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:185:0)
[16:31:41.682] Timer tick, asking Buildbucket for the build status
[16:31:41.930] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:41.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:186:0) after 1m0s
[16:32:41.995] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:186:0)
[16:32:41.995] Timer tick, asking Buildbucket for the build status
[16:32:42.060] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:42.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:187:0) after 1m0s
[16:33:42.071] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:187:0)
[16:33:42.071] Timer tick, asking Buildbucket for the build status
[16:33:42.294] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:42.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:188:0) after 1m0s
[16:34:42.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:188:0)
[16:34:42.409] Timer tick, asking Buildbucket for the build status
[16:34:42.475] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:42.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:189:0) after 1m0s
[16:35:42.512] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:189:0)
[16:35:42.512] Timer tick, asking Buildbucket for the build status
[16:35:42.654] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:42.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:190:0) after 1m0s
[16:36:42.691] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:190:0)
[16:36:42.692] Timer tick, asking Buildbucket for the build status
[16:36:42.759] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:42.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:191:0) after 1m0s
[16:37:42.792] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:191:0)
[16:37:42.792] Timer tick, asking Buildbucket for the build status
[16:37:42.868] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:42.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:192:0) after 1m0s
[16:38:42.853] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:192:0)
[16:38:42.853] Timer tick, asking Buildbucket for the build status
[16:38:42.965] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:42.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:193:0) after 1m0s
[16:39:43.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:193:0)
[16:39:43.153] Timer tick, asking Buildbucket for the build status
[16:39:43.264] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:43.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:194:0) after 1m0s
[16:40:43.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:194:0)
[16:40:43.303] Timer tick, asking Buildbucket for the build status
[16:40:43.372] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:43.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:195:0) after 1m0s
[16:41:43.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:195:0)
[16:41:43.407] Timer tick, asking Buildbucket for the build status
[16:41:43.503] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:43.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:196:0) after 1m0s
[16:42:43.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:196:0)
[16:42:43.556] Timer tick, asking Buildbucket for the build status
[16:42:43.710] Build 8856312905055213584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:43.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021742967235713248:197:0) after 1m0s
[16:42:59.574] Received PubSub notification, asking Buildbucket for the build status
[16:42:59.690] Build 8856312905055213584: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:42:59.690] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612370579066182",
"created_by": "project:chromium",
"created_ts": "1612358866599130",
"failure_reason": "BUILD_FAILURE",
"id": "8856312905055213584",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[89596/89610] CXX obj/chrome/test/captured_sites_interactive_tests/captured_sites_test_utils.obj\\nFAILED: obj/chrome/test/captured_sites_interactive_tests/captured_sites_test_utils.obj\\nC:\\\\b\\\\s\\\\w\\\\ir\\\\cache\\\\goma\\\\client\\\\gomacc.exe ..\\\\..\\\\third_party\\\\llvm-build\\\\Release+Asserts\\\\bin\\\\clang-cl.e...(too long)\\n../../chrome/browser/autofill/captured_sites_test_utils.cc(109,40): error: format specifies type 'char *' but the argument has type 'const std::basic_string\u003cwchar_t\u003e::value_type *' (aka 'const wchar_t *') [-Werror,-Wformat]\\nLOG(INFO) \u003c\u003c base::StringPrintf(msg, command_file_path.value().c_str());\\n~~~ ^~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0\", \"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-2-ksg6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1527, \"got_angle_revision\": \"2e54a1e7fc49e1c1c659d7be659b832954112106\", \"got_dawn_revision\": \"fd5fd05db039710c797ee8bac5e6d999173e5fc3\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0\", \"got_revision_cp\": \"refs/heads/master@{#850085}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"53720ace941d84ac17ae67a94166a363dcf0cacf\", \"got_v8_revision_cp\": \"refs/heads/9.0.123@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_cc946d8c9fe3204026095d74248289703a45d938e0b9766be9cb626e466da04f_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-2-ksg6\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[89596/89610] CXX obj/chrome/test/captured_sites_interactive_tests/captured_sites_test_utils.obj\\nFAILED: obj/chrome/test/captured_sites_interactive_tests/captured_sites_test_utils.obj\\nC:\\\\b\\\\s\\\\w\\\\ir\\\\cache\\\\goma\\\\client\\\\gomacc.exe ..\\\\..\\\\third_party\\\\llvm-build\\\\Release+Asserts\\\\bin\\\\clang-cl.e...(too long)\\n../../chrome/browser/autofill/captured_sites_test_utils.cc(109,40): error: format specifies type 'char *' but the argument has type 'const std::basic_string\u003cwchar_t\u003e::value_type *' (aka 'const wchar_t *') [-Werror,-Wformat]\\nLOG(INFO) \u003c\u003c base::StringPrintf(msg, command_file_path.value().c_str());\\n~~~ ^~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612359114053499",
"status": "COMPLETED",
"status_changed_ts": "1612359114824125",
"tags": [
"build_address:luci.chromium.ci/win-official/1527",
"builder:win-official",
"buildset:commit/git/8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8dcc3dc5c95e730ec8b667c16ed9e582949d7aa0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021742967235713248",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856312905055213584/+/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:5180e561c1448e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612370579293222",
"url": "https://ci.chromium.org/b/8856312905055213584",
"utcnow_ts": "1612370579682534"
}
}
[16:42:59.690] Invocation finished in 3h15m14.554010207s with status FAILED