[20:34:58.606] New invocation is queued and will start shortly
[20:34:59.742] Starting the invocation (attempt 1)
[20:34:59.808] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:34:59.808] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:34:59.808] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022622058278737728",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0abb65116c2d96b2f271886663acbf0672b84d1d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9022622058278737728",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/0abb65116c2d96b2f271886663acbf0672b84d1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0abb65116c2d96b2f271886663acbf0672b84d1d",
"gitiles_ref:refs/heads/master"
]
}
[20:35:00.591] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1611520499928520",
"id": "8857191996225970368",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0abb65116c2d96b2f271886663acbf0672b84d1d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611520500308981",
"tags": [
"build_address:luci.chromium.ci/win-official/1455",
"builder:win-official",
"buildset:commit/git/0abb65116c2d96b2f271886663acbf0672b84d1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0abb65116c2d96b2f271886663acbf0672b84d1d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022622058278737728",
"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/8857191996225970368/+/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": "1611520500309171",
"url": "https://ci.chromium.org/b/8857191996225970368",
"utcnow_ts": "1611520500543923"
}
}
[20:35:00.591] Task URL: https://ci.chromium.org/b/8857191996225970368
[20:35:00.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:2:0) after 1m0s
[20:35:40.070] Received PubSub notification, asking Buildbucket for the build status
[20:35:40.138] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:00.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:2:0)
[20:36:00.615] Timer tick, asking Buildbucket for the build status
[20:36:00.932] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:00.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:4:0) after 1m0s
[20:37:01.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:4:0)
[20:37:01.250] Timer tick, asking Buildbucket for the build status
[20:37:01.320] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:01.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:5:0) after 1m0s
[20:38:01.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:5:0)
[20:38:01.340] Timer tick, asking Buildbucket for the build status
[20:38:01.457] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:01.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:6:0) after 1m0s
[20:39:01.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:6:0)
[20:39:01.774] Timer tick, asking Buildbucket for the build status
[20:39:01.849] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:01.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:7:0) after 1m0s
[20:40:01.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:7:0)
[20:40:01.864] Timer tick, asking Buildbucket for the build status
[20:40:02.195] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:02.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:8:0) after 1m0s
[20:41:02.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:8:0)
[20:41:02.258] Timer tick, asking Buildbucket for the build status
[20:41:02.354] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:02.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:9:0) after 1m0s
[20:42:02.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:9:0)
[20:42:02.414] Timer tick, asking Buildbucket for the build status
[20:42:02.513] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:02.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:10:0) after 1m0s
[20:43:02.549] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:10:0)
[20:43:02.549] Timer tick, asking Buildbucket for the build status
[20:43:02.687] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:02.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:11:0) after 1m0s
[20:44:02.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:11:0)
[20:44:02.731] Timer tick, asking Buildbucket for the build status
[20:44:02.868] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:02.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:12:0) after 1m0s
[20:45:02.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:12:0)
[20:45:02.856] Timer tick, asking Buildbucket for the build status
[20:45:02.957] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:02.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:13:0) after 1m0s
[20:46:03.054] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:13:0)
[20:46:03.054] Timer tick, asking Buildbucket for the build status
[20:46:03.183] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:03.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:14:0) after 1m0s
[20:47:03.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:14:0)
[20:47:03.306] Timer tick, asking Buildbucket for the build status
[20:47:03.622] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:03.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:15:0) after 1m0s
[20:48:03.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:15:0)
[20:48:03.666] Timer tick, asking Buildbucket for the build status
[20:48:03.756] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:03.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:16:0) after 1m0s
[20:49:03.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:16:0)
[20:49:03.808] Timer tick, asking Buildbucket for the build status
[20:49:04.008] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:04.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:17:0) after 1m0s
[20:50:04.084] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:17:0)
[20:50:04.085] Timer tick, asking Buildbucket for the build status
[20:50:04.501] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:04.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:18:0) after 1m0s
[20:51:04.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:18:0)
[20:51:04.655] Timer tick, asking Buildbucket for the build status
[20:51:05.062] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:05.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:19:0) after 1m0s
[20:52:05.091] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:19:0)
[20:52:05.091] Timer tick, asking Buildbucket for the build status
[20:52:05.211] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:05.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:20:0) after 1m0s
[20:53:05.222] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:20:0)
[20:53:05.222] Timer tick, asking Buildbucket for the build status
[20:53:05.281] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:05.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:21:0) after 1m0s
[20:54:05.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:21:0)
[20:54:05.307] Timer tick, asking Buildbucket for the build status
[20:54:05.362] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:05.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:22:0) after 1m0s
[20:55:05.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:22:0)
[20:55:05.417] Timer tick, asking Buildbucket for the build status
[20:55:06.242] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:06.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:23:0) after 1m0s
[20:56:06.296] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:23:0)
[20:56:06.296] Timer tick, asking Buildbucket for the build status
[20:56:06.442] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:06.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:24:0) after 1m0s
[20:57:06.459] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:24:0)
[20:57:06.459] Timer tick, asking Buildbucket for the build status
[20:57:06.639] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:06.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:25:0) after 1m0s
[20:58:06.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:25:0)
[20:58:06.735] Timer tick, asking Buildbucket for the build status
[20:58:06.823] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:06.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:26:0) after 1m0s
[20:59:06.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:26:0)
[20:59:06.832] Timer tick, asking Buildbucket for the build status
[20:59:06.912] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:06.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:27:0) after 1m0s
[21:00:06.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:27:0)
[21:00:06.925] Timer tick, asking Buildbucket for the build status
[21:00:07.021] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:07.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:28:0) after 1m0s
[21:01:07.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:28:0)
[21:01:07.075] Timer tick, asking Buildbucket for the build status
[21:01:07.157] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:07.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:29:0) after 1m0s
[21:02:07.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:29:0)
[21:02:07.238] Timer tick, asking Buildbucket for the build status
[21:02:07.329] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:07.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:30:0) after 1m0s
[21:03:07.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:30:0)
[21:03:07.339] Timer tick, asking Buildbucket for the build status
[21:03:07.527] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:07.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:31:0) after 1m0s
[21:04:07.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:31:0)
[21:04:07.556] Timer tick, asking Buildbucket for the build status
[21:04:07.630] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:07.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:32:0) after 1m0s
[21:05:07.778] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:32:0)
[21:05:07.778] Timer tick, asking Buildbucket for the build status
[21:05:07.908] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:07.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:33:0) after 1m0s
[21:06:07.928] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:33:0)
[21:06:07.928] Timer tick, asking Buildbucket for the build status
[21:06:07.999] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:07.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:34:0) after 1m0s
[21:07:08.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:34:0)
[21:07:08.035] Timer tick, asking Buildbucket for the build status
[21:07:08.135] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:08.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:35:0) after 1m0s
[21:08:08.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:35:0)
[21:08:08.159] Timer tick, asking Buildbucket for the build status
[21:08:08.256] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:08.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:36:0) after 1m0s
[21:09:08.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:36:0)
[21:09:08.288] Timer tick, asking Buildbucket for the build status
[21:09:08.850] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:08.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:37:0) after 1m0s
[21:10:08.895] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:37:0)
[21:10:08.895] Timer tick, asking Buildbucket for the build status
[21:10:08.996] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:08.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:38:0) after 1m0s
[21:11:09.020] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:38:0)
[21:11:09.020] Timer tick, asking Buildbucket for the build status
[21:11:09.091] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:09.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:39:0) after 1m0s
[21:12:09.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:39:0)
[21:12:09.122] Timer tick, asking Buildbucket for the build status
[21:12:09.198] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:09.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:40:0) after 1m0s
[21:13:09.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:40:0)
[21:13:09.211] Timer tick, asking Buildbucket for the build status
[21:13:09.291] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:09.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:41:0) after 1m0s
[21:14:09.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:41:0)
[21:14:09.313] Timer tick, asking Buildbucket for the build status
[21:14:09.478] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:09.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:42:0) after 1m0s
[21:15:09.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:42:0)
[21:15:09.532] Timer tick, asking Buildbucket for the build status
[21:15:09.664] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:09.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:43:0) after 1m0s
[21:16:09.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:43:0)
[21:16:09.688] Timer tick, asking Buildbucket for the build status
[21:16:09.740] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:09.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:44:0) after 1m0s
[21:17:09.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:44:0)
[21:17:09.774] Timer tick, asking Buildbucket for the build status
[21:17:09.832] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:09.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:45:0) after 1m0s
[21:18:09.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:45:0)
[21:18:09.991] Timer tick, asking Buildbucket for the build status
[21:18:10.054] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:10.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:46:0) after 1m0s
[21:19:10.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:46:0)
[21:19:10.141] Timer tick, asking Buildbucket for the build status
[21:19:10.501] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:10.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:47:0) after 1m0s
[21:20:10.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:47:0)
[21:20:10.509] Timer tick, asking Buildbucket for the build status
[21:20:10.989] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:10.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:48:0) after 1m0s
[21:21:11.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:48:0)
[21:21:11.012] Timer tick, asking Buildbucket for the build status
[21:21:11.077] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:11.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:49:0) after 1m0s
[21:22:11.098] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:49:0)
[21:22:11.098] Timer tick, asking Buildbucket for the build status
[21:22:11.304] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:11.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:50:0) after 1m0s
[21:23:11.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:50:0)
[21:23:11.371] Timer tick, asking Buildbucket for the build status
[21:23:11.546] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:11.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:51:0) after 1m0s
[21:24:11.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:51:0)
[21:24:11.565] Timer tick, asking Buildbucket for the build status
[21:24:11.671] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:11.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:52:0) after 1m0s
[21:25:11.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:52:0)
[21:25:11.717] Timer tick, asking Buildbucket for the build status
[21:25:11.816] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:11.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:53:0) after 1m0s
[21:26:11.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:53:0)
[21:26:11.858] Timer tick, asking Buildbucket for the build status
[21:26:12.231] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:12.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:54:0) after 1m0s
[21:27:12.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:54:0)
[21:27:12.348] Timer tick, asking Buildbucket for the build status
[21:27:12.414] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:12.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:55:0) after 1m0s
[21:28:12.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:55:0)
[21:28:12.481] Timer tick, asking Buildbucket for the build status
[21:28:12.551] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:12.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:56:0) after 1m0s
[21:29:12.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:56:0)
[21:29:12.607] Timer tick, asking Buildbucket for the build status
[21:29:12.731] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:12.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:57:0) after 1m0s
[21:30:12.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:57:0)
[21:30:12.761] Timer tick, asking Buildbucket for the build status
[21:30:12.849] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:12.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:58:0) after 1m0s
[21:31:12.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:58:0)
[21:31:12.888] Timer tick, asking Buildbucket for the build status
[21:31:12.941] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:12.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:59:0) after 1m0s
[21:32:13.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:59:0)
[21:32:13.003] Timer tick, asking Buildbucket for the build status
[21:32:13.088] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:13.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:60:0) after 1m0s
[21:33:13.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:60:0)
[21:33:13.144] Timer tick, asking Buildbucket for the build status
[21:33:13.206] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:13.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:61:0) after 1m0s
[21:34:13.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:61:0)
[21:34:13.254] Timer tick, asking Buildbucket for the build status
[21:34:13.362] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:13.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:62:0) after 1m0s
[21:35:13.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:62:0)
[21:35:13.469] Timer tick, asking Buildbucket for the build status
[21:35:13.576] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:13.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:63:0) after 1m0s
[21:36:13.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:63:0)
[21:36:13.607] Timer tick, asking Buildbucket for the build status
[21:36:13.897] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:13.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:64:0) after 1m0s
[21:37:13.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:64:0)
[21:37:13.949] Timer tick, asking Buildbucket for the build status
[21:37:14.011] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:14.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:65:0) after 1m0s
[21:38:14.051] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:65:0)
[21:38:14.051] Timer tick, asking Buildbucket for the build status
[21:38:14.122] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:14.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:66:0) after 1m0s
[21:39:14.154] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:66:0)
[21:39:14.154] Timer tick, asking Buildbucket for the build status
[21:39:14.203] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:14.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:67:0) after 1m0s
[21:40:14.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:67:0)
[21:40:14.350] Timer tick, asking Buildbucket for the build status
[21:40:14.474] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:14.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:68:0) after 1m0s
[21:41:14.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:68:0)
[21:41:14.593] Timer tick, asking Buildbucket for the build status
[21:41:14.651] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:14.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:69:0) after 1m0s
[21:42:14.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:69:0)
[21:42:14.738] Timer tick, asking Buildbucket for the build status
[21:42:14.802] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:14.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:70:0) after 1m0s
[21:43:14.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:70:0)
[21:43:14.825] Timer tick, asking Buildbucket for the build status
[21:43:15.210] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:15.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:71:0) after 1m0s
[21:44:15.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:71:0)
[21:44:15.263] Timer tick, asking Buildbucket for the build status
[21:44:15.418] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:15.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:72:0) after 1m0s
[21:45:15.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:72:0)
[21:45:15.422] Timer tick, asking Buildbucket for the build status
[21:45:15.515] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:15.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:73:0) after 1m0s
[21:46:15.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:73:0)
[21:46:15.599] Timer tick, asking Buildbucket for the build status
[21:46:15.663] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:15.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:74:0) after 1m0s
[21:47:15.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:74:0)
[21:47:15.744] Timer tick, asking Buildbucket for the build status
[21:47:15.799] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:15.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:75:0) after 1m0s
[21:48:15.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:75:0)
[21:48:15.868] Timer tick, asking Buildbucket for the build status
[21:48:15.997] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:15.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:76:0) after 1m0s
[21:49:16.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:76:0)
[21:49:16.057] Timer tick, asking Buildbucket for the build status
[21:49:16.126] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:16.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:77:0) after 1m0s
[21:50:16.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:77:0)
[21:50:16.233] Timer tick, asking Buildbucket for the build status
[21:50:16.333] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:16.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:78:0) after 1m0s
[21:51:16.355] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:78:0)
[21:51:16.355] Timer tick, asking Buildbucket for the build status
[21:51:16.413] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:16.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:79:0) after 1m0s
[21:52:16.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:79:0)
[21:52:16.439] Timer tick, asking Buildbucket for the build status
[21:52:16.499] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:16.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:80:0) after 1m0s
[21:53:16.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:80:0)
[21:53:16.520] Timer tick, asking Buildbucket for the build status
[21:53:16.575] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:16.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:81:0) after 1m0s
[21:54:16.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:81:0)
[21:54:16.595] Timer tick, asking Buildbucket for the build status
[21:54:16.655] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:16.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:82:0) after 1m0s
[21:55:16.767] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:82:0)
[21:55:16.767] Timer tick, asking Buildbucket for the build status
[21:55:16.866] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:16.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:83:0) after 1m0s
[21:56:16.915] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:83:0)
[21:56:16.915] Timer tick, asking Buildbucket for the build status
[21:56:16.986] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:16.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:84:0) after 1m0s
[21:57:17.063] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:84:0)
[21:57:17.063] Timer tick, asking Buildbucket for the build status
[21:57:17.218] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:17.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:85:0) after 1m0s
[21:58:17.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:85:0)
[21:58:17.225] Timer tick, asking Buildbucket for the build status
[21:58:17.280] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:17.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:86:0) after 1m0s
[21:59:17.338] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:86:0)
[21:59:17.338] Timer tick, asking Buildbucket for the build status
[21:59:17.395] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:17.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:87:0) after 1m0s
[22:00:17.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:87:0)
[22:00:17.442] Timer tick, asking Buildbucket for the build status
[22:00:17.633] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:17.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:88:0) after 1m0s
[22:01:17.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:88:0)
[22:01:17.656] Timer tick, asking Buildbucket for the build status
[22:01:17.733] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:17.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:89:0) after 1m0s
[22:02:17.771] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:89:0)
[22:02:17.771] Timer tick, asking Buildbucket for the build status
[22:02:17.901] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:17.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:90:0) after 1m0s
[22:03:17.929] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:90:0)
[22:03:17.929] Timer tick, asking Buildbucket for the build status
[22:03:17.999] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:17.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:91:0) after 1m0s
[22:04:18.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:91:0)
[22:04:18.160] Timer tick, asking Buildbucket for the build status
[22:04:18.362] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:18.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:92:0) after 1m0s
[22:05:18.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:92:0)
[22:05:18.418] Timer tick, asking Buildbucket for the build status
[22:05:18.800] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:18.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:93:0) after 1m0s
[22:06:18.910] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:93:0)
[22:06:18.910] Timer tick, asking Buildbucket for the build status
[22:06:18.982] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:18.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:94:0) after 1m0s
[22:07:19.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:94:0)
[22:07:19.025] Timer tick, asking Buildbucket for the build status
[22:07:19.094] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:19.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:95:0) after 1m0s
[22:08:19.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:95:0)
[22:08:19.116] Timer tick, asking Buildbucket for the build status
[22:08:19.180] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:19.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:96:0) after 1m0s
[22:09:19.202] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:96:0)
[22:09:19.202] Timer tick, asking Buildbucket for the build status
[22:09:19.265] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:19.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:97:0) after 1m0s
[22:10:19.336] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:97:0)
[22:10:19.337] Timer tick, asking Buildbucket for the build status
[22:10:19.839] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:19.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:98:0) after 1m0s
[22:11:19.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:98:0)
[22:11:19.863] Timer tick, asking Buildbucket for the build status
[22:11:19.936] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:19.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:99:0) after 1m0s
[22:12:19.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:99:0)
[22:12:19.919] Timer tick, asking Buildbucket for the build status
[22:12:20.002] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:20.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:100:0) after 1m0s
[22:13:20.061] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:100:0)
[22:13:20.061] Timer tick, asking Buildbucket for the build status
[22:13:20.147] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:20.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:101:0) after 1m0s
[22:14:20.139] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:101:0)
[22:14:20.139] Timer tick, asking Buildbucket for the build status
[22:14:20.235] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:20.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:102:0) after 1m0s
[22:15:20.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:102:0)
[22:15:20.312] Timer tick, asking Buildbucket for the build status
[22:15:20.867] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:20.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:103:0) after 1m0s
[22:16:20.998] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:103:0)
[22:16:20.998] Timer tick, asking Buildbucket for the build status
[22:16:21.072] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:21.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:104:0) after 1m0s
[22:17:21.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:104:0)
[22:17:21.144] Timer tick, asking Buildbucket for the build status
[22:17:21.219] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:21.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:105:0) after 1m0s
[22:18:21.276] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:105:0)
[22:18:21.276] Timer tick, asking Buildbucket for the build status
[22:18:21.501] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:21.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:106:0) after 1m0s
[22:19:21.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:106:0)
[22:19:21.544] Timer tick, asking Buildbucket for the build status
[22:19:21.602] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:21.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:107:0) after 1m0s
[22:20:21.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:107:0)
[22:20:21.695] Timer tick, asking Buildbucket for the build status
[22:20:21.832] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:21.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:108:0) after 1m0s
[22:21:21.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:108:0)
[22:21:21.855] Timer tick, asking Buildbucket for the build status
[22:21:21.934] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:21.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:109:0) after 1m0s
[22:22:21.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:109:0)
[22:22:21.952] Timer tick, asking Buildbucket for the build status
[22:22:22.103] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:22.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:110:0) after 1m0s
[22:23:22.186] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:110:0)
[22:23:22.186] Timer tick, asking Buildbucket for the build status
[22:23:22.279] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:22.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:111:0) after 1m0s
[22:24:22.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:111:0)
[22:24:22.304] Timer tick, asking Buildbucket for the build status
[22:24:22.535] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:22.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:112:0) after 1m0s
[22:25:22.557] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:112:0)
[22:25:22.557] Timer tick, asking Buildbucket for the build status
[22:25:22.725] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:22.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:113:0) after 1m0s
[22:26:22.831] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:113:0)
[22:26:22.831] Timer tick, asking Buildbucket for the build status
[22:26:23.069] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:23.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:114:0) after 1m0s
[22:27:23.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:114:0)
[22:27:23.096] Timer tick, asking Buildbucket for the build status
[22:27:23.402] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:23.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:115:0) after 1m0s
[22:28:23.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:115:0)
[22:28:23.486] Timer tick, asking Buildbucket for the build status
[22:28:23.555] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:23.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:116:0) after 1m0s
[22:29:23.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:116:0)
[22:29:23.531] Timer tick, asking Buildbucket for the build status
[22:29:23.582] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:23.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:117:0) after 1m0s
[22:30:23.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:117:0)
[22:30:23.699] Timer tick, asking Buildbucket for the build status
[22:30:23.912] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:23.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:118:0) after 1m0s
[22:31:23.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:118:0)
[22:31:23.935] Timer tick, asking Buildbucket for the build status
[22:31:24.016] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:24.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:119:0) after 1m0s
[22:32:24.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:119:0)
[22:32:24.038] Timer tick, asking Buildbucket for the build status
[22:32:24.297] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:24.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:120:0) after 1m0s
[22:33:24.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:120:0)
[22:33:24.357] Timer tick, asking Buildbucket for the build status
[22:33:24.535] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:24.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:121:0) after 1m0s
[22:34:24.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:121:0)
[22:34:24.560] Timer tick, asking Buildbucket for the build status
[22:34:24.616] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:24.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:122:0) after 1m0s
[22:35:24.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:122:0)
[22:35:24.640] Timer tick, asking Buildbucket for the build status
[22:35:24.786] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:24.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:123:0) after 1m0s
[22:36:24.841] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:123:0)
[22:36:24.841] Timer tick, asking Buildbucket for the build status
[22:36:24.912] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:24.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:124:0) after 1m0s
[22:37:24.966] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:124:0)
[22:37:24.966] Timer tick, asking Buildbucket for the build status
[22:37:25.026] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:25.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:125:0) after 1m0s
[22:38:25.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:125:0)
[22:38:25.127] Timer tick, asking Buildbucket for the build status
[22:38:25.234] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:25.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:126:0) after 1m0s
[22:39:25.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:126:0)
[22:39:25.208] Timer tick, asking Buildbucket for the build status
[22:39:25.276] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:25.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:127:0) after 1m0s
[22:40:25.308] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:127:0)
[22:40:25.308] Timer tick, asking Buildbucket for the build status
[22:40:25.799] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:25.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:128:0) after 1m0s
[22:41:25.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:128:0)
[22:41:25.827] Timer tick, asking Buildbucket for the build status
[22:41:25.935] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:25.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:129:0) after 1m0s
[22:42:26.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:129:0)
[22:42:26.013] Timer tick, asking Buildbucket for the build status
[22:42:26.118] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:26.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:130:0) after 1m0s
[22:43:26.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:130:0)
[22:43:26.205] Timer tick, asking Buildbucket for the build status
[22:43:26.275] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:26.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:131:0) after 1m0s
[22:44:26.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:131:0)
[22:44:26.411] Timer tick, asking Buildbucket for the build status
[22:44:26.468] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:26.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:132:0) after 1m0s
[22:45:26.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:132:0)
[22:45:26.486] Timer tick, asking Buildbucket for the build status
[22:45:27.064] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:27.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:133:0) after 1m0s
[22:46:27.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:133:0)
[22:46:27.109] Timer tick, asking Buildbucket for the build status
[22:46:27.235] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:27.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:134:0) after 1m0s
[22:47:27.265] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:134:0)
[22:47:27.265] Timer tick, asking Buildbucket for the build status
[22:47:27.391] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:27.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:135:0) after 1m0s
[22:48:27.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:135:0)
[22:48:27.414] Timer tick, asking Buildbucket for the build status
[22:48:27.483] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:27.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:136:0) after 1m0s
[22:49:27.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:136:0)
[22:49:27.518] Timer tick, asking Buildbucket for the build status
[22:49:27.619] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:27.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:137:0) after 1m0s
[22:50:27.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:137:0)
[22:50:27.647] Timer tick, asking Buildbucket for the build status
[22:50:27.783] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:27.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:138:0) after 1m0s
[22:51:27.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:138:0)
[22:51:27.839] Timer tick, asking Buildbucket for the build status
[22:51:28.005] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:28.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:139:0) after 1m0s
[22:52:28.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:139:0)
[22:52:28.027] Timer tick, asking Buildbucket for the build status
[22:52:28.354] Build 8857191996225970368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:28.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022622058278737728:140:0) after 1m0s
[22:53:04.465] Received PubSub notification, asking Buildbucket for the build status
[22:53:04.539] Build 8857191996225970368: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:53:04.539] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611528783755309",
"created_by": "project:chromium",
"created_ts": "1611520499928520",
"id": "8857191996225970368",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0abb65116c2d96b2f271886663acbf0672b84d1d\"}}",
"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\": \"0abb65116c2d96b2f271886663acbf0672b84d1d\", \"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-3-0m1t\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1455, \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"f1f8394de04ea9532de763dfb2f968fc9610683b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"0abb65116c2d96b2f271886663acbf0672b84d1d\", \"got_revision_cp\": \"refs/heads/master@{#846594}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"f2b2f28304b332e59226ee6c0688307ed9982884\", \"got_v8_revision_cp\": \"refs/heads/9.0.57@{#1}\", \"got_webrtc_revision\": \"90776cb0ef72d437983c8d29e5a7f2ac264c107d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33064}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0abb65116c2d96b2f271886663acbf0672b84d1d\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-3-0m1t\"], \"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\": [\"5671-1d9dea8\"], \"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": "1611520539060576",
"status": "COMPLETED",
"status_changed_ts": "1611528784165695",
"tags": [
"build_address:luci.chromium.ci/win-official/1455",
"builder:win-official",
"buildset:commit/git/0abb65116c2d96b2f271886663acbf0672b84d1d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0abb65116c2d96b2f271886663acbf0672b84d1d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022622058278737728",
"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/8857191996225970368/+/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:514eecec42e1b810",
"user_agent:luci-scheduler"
],
"updated_ts": "1611528784170358",
"url": "https://ci.chromium.org/b/8857191996225970368",
"utcnow_ts": "1611528784525881"
}
}
[22:53:04.539] Invocation finished in 2h18m5.951635556s with status SUCCEEDED