[23:28:31.913] New invocation is queued and will start shortly
[23:28:32.997] Starting the invocation (attempt 1)
[23:28:33.034] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:28:33.034] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:28:33.034] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022973526996770608",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"08f415bf70f3dfc637d5108fabd0c8b4e9254567\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9022973526996770608",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/08f415bf70f3dfc637d5108fabd0c8b4e9254567",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/08f415bf70f3dfc637d5108fabd0c8b4e9254567",
"gitiles_ref:refs/heads/master"
]
}
[23:28:34.409] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1611185313208581",
"id": "8857543464975799264",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"08f415bf70f3dfc637d5108fabd0c8b4e9254567\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611185314147033",
"tags": [
"build_address:luci.chromium.ci/win-official/1425",
"builder:win-official",
"buildset:commit/git/08f415bf70f3dfc637d5108fabd0c8b4e9254567",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/08f415bf70f3dfc637d5108fabd0c8b4e9254567",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022973526996770608",
"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/8857543464975799264/+/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": "1611185314147139",
"url": "https://ci.chromium.org/b/8857543464975799264",
"utcnow_ts": "1611185314392342"
}
}
[23:28:34.409] Task URL: https://ci.chromium.org/b/8857543464975799264
[23:28:34.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:2:0) after 1m0s
[23:29:16.774] Received PubSub notification, asking Buildbucket for the build status
[23:29:17.013] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:34.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:2:0)
[23:29:34.458] Timer tick, asking Buildbucket for the build status
[23:29:34.599] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:34.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:4:0) after 1m0s
[23:30:34.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:4:0)
[23:30:34.669] Timer tick, asking Buildbucket for the build status
[23:30:34.756] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:34.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:5:0) after 1m0s
[23:31:34.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:5:0)
[23:31:34.777] Timer tick, asking Buildbucket for the build status
[23:31:34.931] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:34.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:6:0) after 1m0s
[23:32:35.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:6:0)
[23:32:35.035] Timer tick, asking Buildbucket for the build status
[23:32:35.276] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:35.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:7:0) after 1m0s
[23:33:35.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:7:0)
[23:33:35.356] Timer tick, asking Buildbucket for the build status
[23:33:35.432] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:35.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:8:0) after 1m0s
[23:34:35.495] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:8:0)
[23:34:35.495] Timer tick, asking Buildbucket for the build status
[23:34:35.705] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:35.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:9:0) after 1m0s
[23:35:35.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:9:0)
[23:35:35.789] Timer tick, asking Buildbucket for the build status
[23:35:35.858] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:35.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:10:0) after 1m0s
[23:36:36.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:10:0)
[23:36:36.009] Timer tick, asking Buildbucket for the build status
[23:36:36.229] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:36.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:11:0) after 1m0s
[23:37:36.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:11:0)
[23:37:36.275] Timer tick, asking Buildbucket for the build status
[23:37:36.717] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:36.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:12:0) after 1m0s
[23:38:36.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:12:0)
[23:38:36.797] Timer tick, asking Buildbucket for the build status
[23:38:36.891] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:36.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:13:0) after 1m0s
[23:39:36.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:13:0)
[23:39:36.965] Timer tick, asking Buildbucket for the build status
[23:39:37.220] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:37.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:14:0) after 1m0s
[23:40:37.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:14:0)
[23:40:37.289] Timer tick, asking Buildbucket for the build status
[23:40:37.511] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:37.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:15:0) after 1m0s
[23:41:37.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:15:0)
[23:41:37.568] Timer tick, asking Buildbucket for the build status
[23:41:38.622] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:38.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:16:0) after 1m0s
[23:42:38.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:16:0)
[23:42:38.640] Timer tick, asking Buildbucket for the build status
[23:42:39.019] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:39.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:17:0) after 1m0s
[23:43:39.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:17:0)
[23:43:39.147] Timer tick, asking Buildbucket for the build status
[23:43:39.248] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:39.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:18:0) after 1m0s
[23:44:39.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:18:0)
[23:44:39.302] Timer tick, asking Buildbucket for the build status
[23:44:39.433] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:39.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:19:0) after 1m0s
[23:45:39.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:19:0)
[23:45:39.458] Timer tick, asking Buildbucket for the build status
[23:45:39.522] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:39.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:20:0) after 1m0s
[23:46:39.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:20:0)
[23:46:39.573] Timer tick, asking Buildbucket for the build status
[23:46:39.670] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:39.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:21:0) after 1m0s
[23:47:39.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:21:0)
[23:47:39.694] Timer tick, asking Buildbucket for the build status
[23:47:39.966] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:39.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:22:0) after 1m0s
[23:48:39.961] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:22:0)
[23:48:39.961] Timer tick, asking Buildbucket for the build status
[23:48:40.153] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:40.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:23:0) after 1m0s
[23:49:40.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:23:0)
[23:49:40.167] Timer tick, asking Buildbucket for the build status
[23:49:40.250] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:40.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:24:0) after 1m0s
[23:50:40.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:24:0)
[23:50:40.253] Timer tick, asking Buildbucket for the build status
[23:50:40.327] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:40.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:25:0) after 1m0s
[23:51:40.427] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:25:0)
[23:51:40.427] Timer tick, asking Buildbucket for the build status
[23:51:40.554] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:40.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:26:0) after 1m0s
[23:52:40.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:26:0)
[23:52:40.575] Timer tick, asking Buildbucket for the build status
[23:52:40.648] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:40.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:27:0) after 1m0s
[23:53:40.705] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:27:0)
[23:53:40.705] Timer tick, asking Buildbucket for the build status
[23:53:40.808] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:40.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:28:0) after 1m0s
[23:54:40.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:28:0)
[23:54:40.881] Timer tick, asking Buildbucket for the build status
[23:54:41.118] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:41.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:29:0) after 1m0s
[23:55:41.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:29:0)
[23:55:41.183] Timer tick, asking Buildbucket for the build status
[23:55:41.250] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:41.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:30:0) after 1m0s
[23:56:41.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:30:0)
[23:56:41.345] Timer tick, asking Buildbucket for the build status
[23:56:41.420] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:41.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:31:0) after 1m0s
[23:57:41.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:31:0)
[23:57:41.453] Timer tick, asking Buildbucket for the build status
[23:57:41.541] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:41.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:32:0) after 1m0s
[23:58:41.589] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:32:0)
[23:58:41.589] Timer tick, asking Buildbucket for the build status
[23:58:41.727] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:41.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:33:0) after 1m0s
[23:59:41.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:33:0)
[23:59:41.745] Timer tick, asking Buildbucket for the build status
[23:59:42.085] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:42.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:34:0) after 1m0s
[00:00:42.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:34:0)
[00:00:42.225] Timer tick, asking Buildbucket for the build status
[00:00:42.316] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:42.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:35:0) after 1m0s
[00:01:42.333] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:35:0)
[00:01:42.333] Timer tick, asking Buildbucket for the build status
[00:01:42.425] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:42.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:36:0) after 1m0s
[00:02:42.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:36:0)
[00:02:42.522] Timer tick, asking Buildbucket for the build status
[00:02:42.608] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:42.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:37:0) after 1m0s
[00:03:42.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:37:0)
[00:03:42.590] Timer tick, asking Buildbucket for the build status
[00:03:42.830] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:42.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:38:0) after 1m0s
[00:04:42.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:38:0)
[00:04:42.987] Timer tick, asking Buildbucket for the build status
[00:04:43.163] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:43.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:39:0) after 1m0s
[00:05:43.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:39:0)
[00:05:43.176] Timer tick, asking Buildbucket for the build status
[00:05:43.296] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:43.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:40:0) after 1m0s
[00:06:43.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:40:0)
[00:06:43.327] Timer tick, asking Buildbucket for the build status
[00:06:43.517] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:43.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:41:0) after 1m0s
[00:07:43.541] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:41:0)
[00:07:43.541] Timer tick, asking Buildbucket for the build status
[00:07:43.594] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:43.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:42:0) after 1m0s
[00:08:43.654] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:42:0)
[00:08:43.654] Timer tick, asking Buildbucket for the build status
[00:08:43.776] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:43.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:43:0) after 1m0s
[00:09:43.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:43:0)
[00:09:43.763] Timer tick, asking Buildbucket for the build status
[00:09:43.828] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:43.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:44:0) after 1m0s
[00:10:43.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:44:0)
[00:10:43.890] Timer tick, asking Buildbucket for the build status
[00:10:43.964] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:43.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:45:0) after 1m0s
[00:11:44.203] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:45:0)
[00:11:44.203] Timer tick, asking Buildbucket for the build status
[00:11:44.356] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:44.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:46:0) after 1m0s
[00:12:44.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:46:0)
[00:12:44.366] Timer tick, asking Buildbucket for the build status
[00:12:44.679] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:44.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:47:0) after 1m0s
[00:13:44.668] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:47:0)
[00:13:44.668] Timer tick, asking Buildbucket for the build status
[00:13:44.813] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:44.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:48:0) after 1m0s
[00:14:44.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:48:0)
[00:14:44.899] Timer tick, asking Buildbucket for the build status
[00:14:44.968] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:44.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:49:0) after 1m0s
[00:15:45.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:49:0)
[00:15:45.035] Timer tick, asking Buildbucket for the build status
[00:15:45.102] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:45.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:50:0) after 1m0s
[00:16:45.194] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:50:0)
[00:16:45.194] Timer tick, asking Buildbucket for the build status
[00:16:45.261] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:45.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:51:0) after 1m0s
[00:17:45.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:51:0)
[00:17:45.455] Timer tick, asking Buildbucket for the build status
[00:17:45.550] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:45.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:52:0) after 1m0s
[00:18:45.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:52:0)
[00:18:45.652] Timer tick, asking Buildbucket for the build status
[00:18:45.774] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:45.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:53:0) after 1m0s
[00:19:45.859] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:53:0)
[00:19:45.859] Timer tick, asking Buildbucket for the build status
[00:19:45.937] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:45.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:54:0) after 1m0s
[00:20:46.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:54:0)
[00:20:46.185] Timer tick, asking Buildbucket for the build status
[00:20:46.382] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:46.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:55:0) after 1m0s
[00:21:46.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:55:0)
[00:21:46.571] Timer tick, asking Buildbucket for the build status
[00:21:46.718] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:46.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:56:0) after 1m0s
[00:22:46.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:56:0)
[00:22:46.735] Timer tick, asking Buildbucket for the build status
[00:22:46.810] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:46.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:57:0) after 1m0s
[00:23:47.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:57:0)
[00:23:47.027] Timer tick, asking Buildbucket for the build status
[00:23:47.164] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:47.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:58:0) after 1m0s
[00:24:47.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:58:0)
[00:24:47.264] Timer tick, asking Buildbucket for the build status
[00:24:47.356] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:47.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:59:0) after 1m0s
[00:25:47.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:59:0)
[00:25:47.483] Timer tick, asking Buildbucket for the build status
[00:25:47.568] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:47.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:60:0) after 1m0s
[00:26:47.661] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:60:0)
[00:26:47.661] Timer tick, asking Buildbucket for the build status
[00:26:47.719] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:47.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:61:0) after 1m0s
[00:27:47.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:61:0)
[00:27:47.919] Timer tick, asking Buildbucket for the build status
[00:27:47.983] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:47.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:62:0) after 1m0s
[00:28:48.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:62:0)
[00:28:48.340] Timer tick, asking Buildbucket for the build status
[00:28:48.501] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:48.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:63:0) after 1m0s
[00:29:48.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:63:0)
[00:29:48.742] Timer tick, asking Buildbucket for the build status
[00:29:49.022] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:49.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:64:0) after 1m0s
[00:30:49.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:64:0)
[00:30:49.187] Timer tick, asking Buildbucket for the build status
[00:30:49.269] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:49.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:65:0) after 1m0s
[00:31:49.344] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:65:0)
[00:31:49.344] Timer tick, asking Buildbucket for the build status
[00:31:49.406] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:49.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:66:0) after 1m0s
[00:32:49.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:66:0)
[00:32:49.429] Timer tick, asking Buildbucket for the build status
[00:32:49.501] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:49.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:67:0) after 1m0s
[00:33:49.557] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:67:0)
[00:33:49.557] Timer tick, asking Buildbucket for the build status
[00:33:49.928] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:49.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:68:0) after 1m0s
[00:34:49.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:68:0)
[00:34:49.948] Timer tick, asking Buildbucket for the build status
[00:34:50.356] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:50.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:69:0) after 1m0s
[00:35:50.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:69:0)
[00:35:50.432] Timer tick, asking Buildbucket for the build status
[00:35:50.508] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:50.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:70:0) after 1m0s
[00:36:50.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:70:0)
[00:36:50.539] Timer tick, asking Buildbucket for the build status
[00:36:50.620] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:50.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:71:0) after 1m0s
[00:37:50.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:71:0)
[00:37:50.770] Timer tick, asking Buildbucket for the build status
[00:37:50.953] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:50.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:72:0) after 1m0s
[00:38:51.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:72:0)
[00:38:51.029] Timer tick, asking Buildbucket for the build status
[00:38:51.134] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:51.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:73:0) after 1m0s
[00:39:51.284] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:73:0)
[00:39:51.284] Timer tick, asking Buildbucket for the build status
[00:39:51.367] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:51.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:74:0) after 1m0s
[00:40:51.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:74:0)
[00:40:51.448] Timer tick, asking Buildbucket for the build status
[00:40:51.539] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:51.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:75:0) after 1m0s
[00:41:51.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:75:0)
[00:41:51.572] Timer tick, asking Buildbucket for the build status
[00:41:51.637] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:51.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:76:0) after 1m0s
[00:42:51.791] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:76:0)
[00:42:51.791] Timer tick, asking Buildbucket for the build status
[00:42:51.861] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:51.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:77:0) after 1m0s
[00:43:52.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:77:0)
[00:43:52.016] Timer tick, asking Buildbucket for the build status
[00:43:52.296] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:52.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:78:0) after 1m0s
[00:44:52.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:78:0)
[00:44:52.466] Timer tick, asking Buildbucket for the build status
[00:44:52.529] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:52.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:79:0) after 1m0s
[00:45:52.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:79:0)
[00:45:52.613] Timer tick, asking Buildbucket for the build status
[00:45:53.068] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:53.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:80:0) after 1m0s
[00:46:53.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:80:0)
[00:46:53.096] Timer tick, asking Buildbucket for the build status
[00:46:53.165] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:53.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:81:0) after 1m0s
[00:47:53.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:81:0)
[00:47:53.283] Timer tick, asking Buildbucket for the build status
[00:47:53.394] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:53.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:82:0) after 1m0s
[00:48:53.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:82:0)
[00:48:53.475] Timer tick, asking Buildbucket for the build status
[00:48:53.592] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:53.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:83:0) after 1m0s
[00:49:53.681] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:83:0)
[00:49:53.681] Timer tick, asking Buildbucket for the build status
[00:49:53.744] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:53.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:84:0) after 1m0s
[00:50:53.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:84:0)
[00:50:53.889] Timer tick, asking Buildbucket for the build status
[00:50:53.963] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:53.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:85:0) after 1m0s
[00:51:54.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:85:0)
[00:51:54.075] Timer tick, asking Buildbucket for the build status
[00:51:54.347] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:54.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:86:0) after 1m0s
[00:52:54.448] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:86:0)
[00:52:54.448] Timer tick, asking Buildbucket for the build status
[00:52:54.547] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:54.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:87:0) after 1m0s
[00:53:54.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:87:0)
[00:53:54.630] Timer tick, asking Buildbucket for the build status
[00:53:54.741] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:54.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:88:0) after 1m0s
[00:54:54.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:88:0)
[00:54:54.817] Timer tick, asking Buildbucket for the build status
[00:54:54.887] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:54.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:89:0) after 1m0s
[00:55:54.944] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:89:0)
[00:55:54.944] Timer tick, asking Buildbucket for the build status
[00:55:55.164] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:55.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:90:0) after 1m0s
[00:56:55.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:90:0)
[00:56:55.254] Timer tick, asking Buildbucket for the build status
[00:56:55.325] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:55.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:91:0) after 1m0s
[00:57:55.324] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:91:0)
[00:57:55.324] Timer tick, asking Buildbucket for the build status
[00:57:55.383] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:55.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:92:0) after 1m0s
[00:58:55.487] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:92:0)
[00:58:55.487] Timer tick, asking Buildbucket for the build status
[00:58:55.804] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:55.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:93:0) after 1m0s
[00:59:55.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:93:0)
[00:59:55.945] Timer tick, asking Buildbucket for the build status
[00:59:56.118] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:56.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:94:0) after 1m0s
[01:00:56.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:94:0)
[01:00:56.229] Timer tick, asking Buildbucket for the build status
[01:00:56.306] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:56.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:95:0) after 1m0s
[01:01:56.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:95:0)
[01:01:56.337] Timer tick, asking Buildbucket for the build status
[01:01:56.818] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:56.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:96:0) after 1m0s
[01:02:56.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:96:0)
[01:02:56.832] Timer tick, asking Buildbucket for the build status
[01:02:57.012] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:57.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:97:0) after 1m0s
[01:03:57.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:97:0)
[01:03:57.021] Timer tick, asking Buildbucket for the build status
[01:03:57.334] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:57.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:98:0) after 1m0s
[01:04:57.344] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:98:0)
[01:04:57.344] Timer tick, asking Buildbucket for the build status
[01:04:57.451] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:57.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:99:0) after 1m0s
[01:05:57.536] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:99:0)
[01:05:57.536] Timer tick, asking Buildbucket for the build status
[01:05:57.687] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:57.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:100:0) after 1m0s
[01:06:57.733] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:100:0)
[01:06:57.733] Timer tick, asking Buildbucket for the build status
[01:06:57.800] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:57.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:101:0) after 1m0s
[01:07:57.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:101:0)
[01:07:57.836] Timer tick, asking Buildbucket for the build status
[01:07:57.902] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:57.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:102:0) after 1m0s
[01:08:57.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:102:0)
[01:08:57.927] Timer tick, asking Buildbucket for the build status
[01:08:58.033] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:58.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:103:0) after 1m0s
[01:09:58.119] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:103:0)
[01:09:58.119] Timer tick, asking Buildbucket for the build status
[01:09:58.174] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:58.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:104:0) after 1m0s
[01:10:58.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:104:0)
[01:10:58.244] Timer tick, asking Buildbucket for the build status
[01:10:58.317] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:58.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:105:0) after 1m0s
[01:11:58.365] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:105:0)
[01:11:58.365] Timer tick, asking Buildbucket for the build status
[01:11:58.428] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:58.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:106:0) after 1m0s
[01:12:58.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:106:0)
[01:12:58.502] Timer tick, asking Buildbucket for the build status
[01:12:58.568] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:58.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:107:0) after 1m0s
[01:13:58.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:107:0)
[01:13:58.622] Timer tick, asking Buildbucket for the build status
[01:13:58.727] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:58.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:108:0) after 1m0s
[01:14:58.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:108:0)
[01:14:58.787] Timer tick, asking Buildbucket for the build status
[01:14:58.858] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:58.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:109:0) after 1m0s
[01:15:58.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:109:0)
[01:15:58.891] Timer tick, asking Buildbucket for the build status
[01:15:59.156] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:59.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:110:0) after 1m0s
[01:16:59.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:110:0)
[01:16:59.221] Timer tick, asking Buildbucket for the build status
[01:16:59.282] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:59.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:111:0) after 1m0s
[01:17:59.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:111:0)
[01:17:59.347] Timer tick, asking Buildbucket for the build status
[01:17:59.423] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:59.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:112:0) after 1m0s
[01:18:59.550] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:112:0)
[01:18:59.550] Timer tick, asking Buildbucket for the build status
[01:18:59.705] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:59.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:113:0) after 1m0s
[01:19:59.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:113:0)
[01:19:59.814] Timer tick, asking Buildbucket for the build status
[01:19:59.879] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:59.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:114:0) after 1m0s
[01:20:59.931] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:114:0)
[01:20:59.931] Timer tick, asking Buildbucket for the build status
[01:20:59.994] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:59.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:115:0) after 1m0s
[01:22:00.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:115:0)
[01:22:00.025] Timer tick, asking Buildbucket for the build status
[01:22:00.290] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:00.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:116:0) after 1m0s
[01:23:00.582] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:116:0)
[01:23:00.582] Timer tick, asking Buildbucket for the build status
[01:23:00.644] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:00.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:117:0) after 1m0s
[01:24:00.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:117:0)
[01:24:00.847] Timer tick, asking Buildbucket for the build status
[01:24:00.984] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:00.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:118:0) after 1m0s
[01:25:01.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:118:0)
[01:25:01.151] Timer tick, asking Buildbucket for the build status
[01:25:01.219] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:01.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:119:0) after 1m0s
[01:26:01.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:119:0)
[01:26:01.267] Timer tick, asking Buildbucket for the build status
[01:26:01.349] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:01.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:120:0) after 1m0s
[01:27:01.374] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:120:0)
[01:27:01.374] Timer tick, asking Buildbucket for the build status
[01:27:01.497] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:01.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:121:0) after 1m0s
[01:28:01.536] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:121:0)
[01:28:01.536] Timer tick, asking Buildbucket for the build status
[01:28:01.960] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:01.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:122:0) after 1m0s
[01:29:02.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:122:0)
[01:29:02.016] Timer tick, asking Buildbucket for the build status
[01:29:02.129] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:02.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:123:0) after 1m0s
[01:30:02.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:123:0)
[01:30:02.259] Timer tick, asking Buildbucket for the build status
[01:30:02.339] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:02.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:124:0) after 1m0s
[01:31:02.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:124:0)
[01:31:02.367] Timer tick, asking Buildbucket for the build status
[01:31:02.596] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:02.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:125:0) after 1m0s
[01:32:02.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:125:0)
[01:32:02.642] Timer tick, asking Buildbucket for the build status
[01:32:02.719] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:02.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:126:0) after 1m0s
[01:33:02.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:126:0)
[01:33:02.770] Timer tick, asking Buildbucket for the build status
[01:33:02.828] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:02.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:127:0) after 1m0s
[01:34:02.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:127:0)
[01:34:02.891] Timer tick, asking Buildbucket for the build status
[01:34:03.497] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:03.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:128:0) after 1m0s
[01:35:03.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:128:0)
[01:35:03.594] Timer tick, asking Buildbucket for the build status
[01:35:03.656] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:03.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:129:0) after 1m0s
[01:36:03.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:129:0)
[01:36:03.694] Timer tick, asking Buildbucket for the build status
[01:36:03.844] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:03.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:130:0) after 1m0s
[01:37:03.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:130:0)
[01:37:03.880] Timer tick, asking Buildbucket for the build status
[01:37:04.027] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:04.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:131:0) after 1m0s
[01:38:04.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:131:0)
[01:38:04.189] Timer tick, asking Buildbucket for the build status
[01:38:04.325] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:04.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:132:0) after 1m0s
[01:39:04.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:132:0)
[01:39:04.342] Timer tick, asking Buildbucket for the build status
[01:39:04.657] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:04.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:133:0) after 1m0s
[01:40:04.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:133:0)
[01:40:04.779] Timer tick, asking Buildbucket for the build status
[01:40:04.986] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:04.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:134:0) after 1m0s
[01:41:05.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:134:0)
[01:41:05.356] Timer tick, asking Buildbucket for the build status
[01:41:05.639] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:05.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:135:0) after 1m0s
[01:42:05.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:135:0)
[01:42:05.745] Timer tick, asking Buildbucket for the build status
[01:42:05.905] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:05.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:136:0) after 1m0s
[01:43:05.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:136:0)
[01:43:05.933] Timer tick, asking Buildbucket for the build status
[01:43:05.994] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:05.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:137:0) after 1m0s
[01:44:06.154] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:137:0)
[01:44:06.154] Timer tick, asking Buildbucket for the build status
[01:44:06.673] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:06.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:138:0) after 1m0s
[01:45:06.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:138:0)
[01:45:06.712] Timer tick, asking Buildbucket for the build status
[01:45:06.797] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:06.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:139:0) after 1m0s
[01:46:07.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:139:0)
[01:46:07.027] Timer tick, asking Buildbucket for the build status
[01:46:07.105] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:07.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:140:0) after 1m0s
[01:47:07.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:140:0)
[01:47:07.245] Timer tick, asking Buildbucket for the build status
[01:47:07.313] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:07.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:141:0) after 1m0s
[01:48:07.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:141:0)
[01:48:07.601] Timer tick, asking Buildbucket for the build status
[01:48:07.672] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:07.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:142:0) after 1m0s
[01:49:07.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:142:0)
[01:49:07.864] Timer tick, asking Buildbucket for the build status
[01:49:08.041] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:08.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:143:0) after 1m0s
[01:50:08.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:143:0)
[01:50:08.066] Timer tick, asking Buildbucket for the build status
[01:50:08.146] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:08.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:144:0) after 1m0s
[01:51:08.325] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:144:0)
[01:51:08.325] Timer tick, asking Buildbucket for the build status
[01:51:08.393] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:08.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:145:0) after 1m0s
[01:52:08.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:145:0)
[01:52:08.490] Timer tick, asking Buildbucket for the build status
[01:52:08.566] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:08.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:146:0) after 1m0s
[01:53:08.659] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:146:0)
[01:53:08.659] Timer tick, asking Buildbucket for the build status
[01:53:08.757] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:08.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:147:0) after 1m0s
[01:54:08.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:147:0)
[01:54:08.850] Timer tick, asking Buildbucket for the build status
[01:54:09.056] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:09.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:148:0) after 1m0s
[01:55:09.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:148:0)
[01:55:09.192] Timer tick, asking Buildbucket for the build status
[01:55:09.612] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:09.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:149:0) after 1m0s
[01:56:09.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:149:0)
[01:56:09.765] Timer tick, asking Buildbucket for the build status
[01:56:09.842] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:09.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:150:0) after 1m0s
[01:57:09.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:150:0)
[01:57:09.939] Timer tick, asking Buildbucket for the build status
[01:57:10.032] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:10.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:151:0) after 1m0s
[01:58:10.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:151:0)
[01:58:10.116] Timer tick, asking Buildbucket for the build status
[01:58:10.184] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:10.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:152:0) after 1m0s
[01:59:10.216] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:152:0)
[01:59:10.216] Timer tick, asking Buildbucket for the build status
[01:59:10.356] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:10.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:153:0) after 1m0s
[02:00:10.459] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:153:0)
[02:00:10.459] Timer tick, asking Buildbucket for the build status
[02:00:10.532] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:10.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:154:0) after 1m0s
[02:01:10.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:154:0)
[02:01:10.572] Timer tick, asking Buildbucket for the build status
[02:01:10.852] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:10.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:155:0) after 1m0s
[02:02:10.929] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:155:0)
[02:02:10.929] Timer tick, asking Buildbucket for the build status
[02:02:11.195] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:11.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:156:0) after 1m0s
[02:03:11.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:156:0)
[02:03:11.233] Timer tick, asking Buildbucket for the build status
[02:03:11.318] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:11.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:157:0) after 1m0s
[02:04:11.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:157:0)
[02:04:11.425] Timer tick, asking Buildbucket for the build status
[02:04:11.551] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:11.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:158:0) after 1m0s
[02:05:11.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:158:0)
[02:05:11.658] Timer tick, asking Buildbucket for the build status
[02:05:11.876] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:11.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:159:0) after 1m0s
[02:06:11.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:159:0)
[02:06:11.927] Timer tick, asking Buildbucket for the build status
[02:06:11.988] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:11.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:160:0) after 1m0s
[02:07:12.018] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:160:0)
[02:07:12.018] Timer tick, asking Buildbucket for the build status
[02:07:12.467] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:12.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:161:0) after 1m0s
[02:08:12.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:161:0)
[02:08:12.545] Timer tick, asking Buildbucket for the build status
[02:08:12.771] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:12.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:162:0) after 1m0s
[02:09:12.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:162:0)
[02:09:12.803] Timer tick, asking Buildbucket for the build status
[02:09:12.922] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:12.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:163:0) after 1m0s
[02:10:12.950] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:163:0)
[02:10:12.951] Timer tick, asking Buildbucket for the build status
[02:10:13.066] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:13.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:164:0) after 1m0s
[02:11:13.129] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:164:0)
[02:11:13.129] Timer tick, asking Buildbucket for the build status
[02:11:13.309] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:13.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:165:0) after 1m0s
[02:12:13.496] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:165:0)
[02:12:13.496] Timer tick, asking Buildbucket for the build status
[02:12:13.692] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:13.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:166:0) after 1m0s
[02:13:13.711] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:166:0)
[02:13:13.711] Timer tick, asking Buildbucket for the build status
[02:13:13.853] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:13.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:167:0) after 1m0s
[02:14:13.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:167:0)
[02:14:13.872] Timer tick, asking Buildbucket for the build status
[02:14:14.002] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:14.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:168:0) after 1m0s
[02:15:14.040] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:168:0)
[02:15:14.040] Timer tick, asking Buildbucket for the build status
[02:15:14.303] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:14.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:169:0) after 1m0s
[02:16:14.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:169:0)
[02:16:14.405] Timer tick, asking Buildbucket for the build status
[02:16:14.507] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:14.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:170:0) after 1m0s
[02:17:14.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:170:0)
[02:17:14.551] Timer tick, asking Buildbucket for the build status
[02:17:14.633] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:14.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:171:0) after 1m0s
[02:18:14.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:171:0)
[02:18:14.738] Timer tick, asking Buildbucket for the build status
[02:18:14.817] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:14.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:172:0) after 1m0s
[02:19:14.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:172:0)
[02:19:14.854] Timer tick, asking Buildbucket for the build status
[02:19:14.970] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:14.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:173:0) after 1m0s
[02:20:15.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:173:0)
[02:20:15.017] Timer tick, asking Buildbucket for the build status
[02:20:15.117] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:15.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:174:0) after 1m0s
[02:21:15.153] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:174:0)
[02:21:15.154] Timer tick, asking Buildbucket for the build status
[02:21:15.205] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:15.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:175:0) after 1m0s
[02:22:15.234] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:175:0)
[02:22:15.234] Timer tick, asking Buildbucket for the build status
[02:22:15.677] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:15.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:176:0) after 1m0s
[02:23:15.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:176:0)
[02:23:15.809] Timer tick, asking Buildbucket for the build status
[02:23:15.936] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:15.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:177:0) after 1m0s
[02:24:16.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:177:0)
[02:24:16.005] Timer tick, asking Buildbucket for the build status
[02:24:16.498] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:16.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:178:0) after 1m0s
[02:25:16.586] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:178:0)
[02:25:16.586] Timer tick, asking Buildbucket for the build status
[02:25:16.816] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:16.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:179:0) after 1m0s
[02:26:16.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:179:0)
[02:26:16.849] Timer tick, asking Buildbucket for the build status
[02:26:16.921] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:16.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:180:0) after 1m0s
[02:27:17.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:180:0)
[02:27:17.017] Timer tick, asking Buildbucket for the build status
[02:27:17.298] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:17.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:181:0) after 1m0s
[02:28:17.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:181:0)
[02:28:17.357] Timer tick, asking Buildbucket for the build status
[02:28:17.616] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:17.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:182:0) after 1m0s
[02:29:17.680] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:182:0)
[02:29:17.680] Timer tick, asking Buildbucket for the build status
[02:29:18.491] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:18.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:183:0) after 1m0s
[02:30:18.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:183:0)
[02:30:18.551] Timer tick, asking Buildbucket for the build status
[02:30:18.631] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:18.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:184:0) after 1m0s
[02:31:18.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:184:0)
[02:31:18.686] Timer tick, asking Buildbucket for the build status
[02:31:18.748] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:18.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:185:0) after 1m0s
[02:32:18.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:185:0)
[02:32:18.864] Timer tick, asking Buildbucket for the build status
[02:32:18.956] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:18.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:186:0) after 1m0s
[02:33:19.001] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:186:0)
[02:33:19.001] Timer tick, asking Buildbucket for the build status
[02:33:19.304] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:19.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:187:0) after 1m0s
[02:34:19.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:187:0)
[02:34:19.387] Timer tick, asking Buildbucket for the build status
[02:34:19.489] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:19.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:188:0) after 1m0s
[02:35:19.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:188:0)
[02:35:19.598] Timer tick, asking Buildbucket for the build status
[02:35:19.826] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:19.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:189:0) after 1m0s
[02:36:19.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:189:0)
[02:36:19.881] Timer tick, asking Buildbucket for the build status
[02:36:20.127] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:20.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:190:0) after 1m0s
[02:37:20.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:190:0)
[02:37:20.258] Timer tick, asking Buildbucket for the build status
[02:37:20.376] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:20.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:191:0) after 1m0s
[02:38:20.433] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:191:0)
[02:38:20.433] Timer tick, asking Buildbucket for the build status
[02:38:20.496] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:20.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:192:0) after 1m0s
[02:39:20.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:192:0)
[02:39:20.763] Timer tick, asking Buildbucket for the build status
[02:39:21.100] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:21.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:193:0) after 1m0s
[02:40:21.334] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:193:0)
[02:40:21.334] Timer tick, asking Buildbucket for the build status
[02:40:21.402] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:21.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:194:0) after 1m0s
[02:41:21.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:194:0)
[02:41:21.527] Timer tick, asking Buildbucket for the build status
[02:41:21.597] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:21.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:195:0) after 1m0s
[02:42:21.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:195:0)
[02:42:21.773] Timer tick, asking Buildbucket for the build status
[02:42:21.835] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:21.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:196:0) after 1m0s
[02:43:22.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:196:0)
[02:43:22.230] Timer tick, asking Buildbucket for the build status
[02:43:22.292] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:22.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:197:0) after 1m0s
[02:44:22.489] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:197:0)
[02:44:22.489] Timer tick, asking Buildbucket for the build status
[02:44:22.665] Build 8857543464975799264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:22.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022973526996770608:198:0) after 1m0s
[02:44:45.020] Received PubSub notification, asking Buildbucket for the build status
[02:44:45.088] Build 8857543464975799264: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:44:45.088] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611197084408059",
"created_by": "project:chromium",
"created_ts": "1611185313208581",
"id": "8857543464975799264",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"08f415bf70f3dfc637d5108fabd0c8b4e9254567\"}}",
"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\": \"08f415bf70f3dfc637d5108fabd0c8b4e9254567\", \"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-8-fqoc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1425, \"got_angle_revision\": \"ea71c6b63417af438afaf26a886b03621d85f14f\", \"got_dawn_revision\": \"064f33e4416ed498c91137f518fedb56c325b99b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"08f415bf70f3dfc637d5108fabd0c8b4e9254567\", \"got_revision_cp\": \"refs/heads/master@{#845396}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"e27e7488e4ad0ef2099149c66e8b57742e9d5a1c\", \"got_v8_revision\": \"1d681a1a9f5b66ce7c18d756f80e1c3afab911d5\", \"got_v8_revision_cp\": \"refs/heads/9.0.28@{#1}\", \"got_webrtc_revision\": \"0be1846477949c472a7a2bf42134a7bb08570ac4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33046}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"08f415bf70f3dfc637d5108fabd0c8b4e9254567\"}, \"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-8-fqoc\"], \"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\": [\"5666-679c9bd\"], \"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": "1611185355687423",
"status": "COMPLETED",
"status_changed_ts": "1611197084720043",
"tags": [
"build_address:luci.chromium.ci/win-official/1425",
"builder:win-official",
"buildset:commit/git/08f415bf70f3dfc637d5108fabd0c8b4e9254567",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/08f415bf70f3dfc637d5108fabd0c8b4e9254567",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022973526996770608",
"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/8857543464975799264/+/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:513af26486b2c110",
"user_agent:luci-scheduler"
],
"updated_ts": "1611197084724551",
"url": "https://ci.chromium.org/b/8857543464975799264",
"utcnow_ts": "1611197085081057"
}
}
[02:44:45.088] Invocation finished in 3h16m13.190727525s with status SUCCEEDED