[15:06:55.051] New invocation is queued and will start shortly
[15:06:56.211] Starting the invocation (attempt 1)
[15:06:56.268] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:06:56.275] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:06:56.275] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023458070687473632",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"749006de9966fb0229f7361a12455728bbd67bfc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9023458070687473632",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/749006de9966fb0229f7361a12455728bbd67bfc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/749006de9966fb0229f7361a12455728bbd67bfc",
"gitiles_ref:refs/heads/master"
]
}
[15:06:58.447] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610723216495054",
"id": "8858028008498750128",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"749006de9966fb0229f7361a12455728bbd67bfc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610723218149210",
"tags": [
"build_address:luci.chromium.ci/win-official/1392",
"builder:win-official",
"buildset:commit/git/749006de9966fb0229f7361a12455728bbd67bfc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/749006de9966fb0229f7361a12455728bbd67bfc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023458070687473632",
"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/8858028008498750128/+/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": "1610723218149318",
"url": "https://ci.chromium.org/b/8858028008498750128",
"utcnow_ts": "1610723218376266"
}
}
[15:06:58.448] Task URL: https://ci.chromium.org/b/8858028008498750128
[15:06:58.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:2:0) after 1m0s
[15:07:39.957] Received PubSub notification, asking Buildbucket for the build status
[15:07:40.105] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:58.550] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:2:0)
[15:07:58.550] Timer tick, asking Buildbucket for the build status
[15:07:58.703] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:58.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:4:0) after 1m0s
[15:08:58.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:4:0)
[15:08:58.760] Timer tick, asking Buildbucket for the build status
[15:08:59.210] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:59.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:5:0) after 1m0s
[15:09:59.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:5:0)
[15:09:59.253] Timer tick, asking Buildbucket for the build status
[15:09:59.322] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:59.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:6:0) after 1m0s
[15:10:59.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:6:0)
[15:10:59.362] Timer tick, asking Buildbucket for the build status
[15:10:59.572] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:59.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:7:0) after 1m0s
[15:11:59.591] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:7:0)
[15:11:59.591] Timer tick, asking Buildbucket for the build status
[15:11:59.709] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:59.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:8:0) after 1m0s
[15:12:59.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:8:0)
[15:12:59.737] Timer tick, asking Buildbucket for the build status
[15:13:00.167] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:00.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:9:0) after 1m0s
[15:14:00.191] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:9:0)
[15:14:00.191] Timer tick, asking Buildbucket for the build status
[15:14:00.344] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:00.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:10:0) after 1m0s
[15:15:00.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:10:0)
[15:15:00.485] Timer tick, asking Buildbucket for the build status
[15:15:00.580] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:00.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:11:0) after 1m0s
[15:16:00.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:11:0)
[15:16:00.651] Timer tick, asking Buildbucket for the build status
[15:16:00.756] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:00.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:12:0) after 1m0s
[15:17:00.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:12:0)
[15:17:00.788] Timer tick, asking Buildbucket for the build status
[15:17:00.926] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:00.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:13:0) after 1m0s
[15:18:01.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:13:0)
[15:18:01.022] Timer tick, asking Buildbucket for the build status
[15:18:01.133] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:01.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:14:0) after 1m0s
[15:19:01.341] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:14:0)
[15:19:01.341] Timer tick, asking Buildbucket for the build status
[15:19:01.831] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:01.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:15:0) after 1m0s
[15:20:01.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:15:0)
[15:20:01.898] Timer tick, asking Buildbucket for the build status
[15:20:02.012] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:02.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:16:0) after 1m0s
[15:21:02.128] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:16:0)
[15:21:02.128] Timer tick, asking Buildbucket for the build status
[15:21:02.251] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:02.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:17:0) after 1m0s
[15:22:02.355] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:17:0)
[15:22:02.355] Timer tick, asking Buildbucket for the build status
[15:22:02.717] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:02.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:18:0) after 1m0s
[15:23:02.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:18:0)
[15:23:02.738] Timer tick, asking Buildbucket for the build status
[15:23:02.793] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:02.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:19:0) after 1m0s
[15:24:02.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:19:0)
[15:24:02.845] Timer tick, asking Buildbucket for the build status
[15:24:02.917] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:02.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:20:0) after 1m0s
[15:25:02.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:20:0)
[15:25:02.924] Timer tick, asking Buildbucket for the build status
[15:25:03.141] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:03.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:21:0) after 1m0s
[15:26:03.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:21:0)
[15:26:03.173] Timer tick, asking Buildbucket for the build status
[15:26:03.331] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:03.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:22:0) after 1m0s
[15:27:03.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:22:0)
[15:27:03.371] Timer tick, asking Buildbucket for the build status
[15:27:03.859] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:03.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:23:0) after 1m0s
[15:28:03.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:23:0)
[15:28:03.908] Timer tick, asking Buildbucket for the build status
[15:28:04.292] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:04.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:24:0) after 1m0s
[15:29:04.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:24:0)
[15:29:04.313] Timer tick, asking Buildbucket for the build status
[15:29:04.688] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:04.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:25:0) after 1m0s
[15:30:04.749] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:25:0)
[15:30:04.749] Timer tick, asking Buildbucket for the build status
[15:30:04.830] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:04.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:26:0) after 1m0s
[15:31:04.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:26:0)
[15:31:04.854] Timer tick, asking Buildbucket for the build status
[15:31:04.930] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:04.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:27:0) after 1m0s
[15:32:04.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:27:0)
[15:32:04.960] Timer tick, asking Buildbucket for the build status
[15:32:05.256] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:05.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:28:0) after 1m0s
[15:33:05.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:28:0)
[15:33:05.366] Timer tick, asking Buildbucket for the build status
[15:33:05.435] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:05.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:29:0) after 1m0s
[15:34:05.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:29:0)
[15:34:05.552] Timer tick, asking Buildbucket for the build status
[15:34:05.895] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:05.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:30:0) after 1m0s
[15:35:05.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:30:0)
[15:35:05.924] Timer tick, asking Buildbucket for the build status
[15:35:06.009] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:06.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:31:0) after 1m0s
[15:36:06.050] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:31:0)
[15:36:06.050] Timer tick, asking Buildbucket for the build status
[15:36:06.116] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:06.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:32:0) after 1m0s
[15:37:06.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:32:0)
[15:37:06.204] Timer tick, asking Buildbucket for the build status
[15:37:06.419] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:06.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:33:0) after 1m0s
[15:38:06.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:33:0)
[15:38:06.466] Timer tick, asking Buildbucket for the build status
[15:38:06.614] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:06.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:34:0) after 1m0s
[15:39:06.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:34:0)
[15:39:06.660] Timer tick, asking Buildbucket for the build status
[15:39:06.718] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:06.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:35:0) after 1m0s
[15:40:06.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:35:0)
[15:40:06.759] Timer tick, asking Buildbucket for the build status
[15:40:06.829] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:06.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:36:0) after 1m0s
[15:41:06.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:36:0)
[15:41:06.858] Timer tick, asking Buildbucket for the build status
[15:41:06.933] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:06.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:37:0) after 1m0s
[15:42:06.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:37:0)
[15:42:06.997] Timer tick, asking Buildbucket for the build status
[15:42:07.323] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:07.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:38:0) after 1m0s
[15:43:07.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:38:0)
[15:43:07.407] Timer tick, asking Buildbucket for the build status
[15:43:07.583] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:07.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:39:0) after 1m0s
[15:44:07.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:39:0)
[15:44:07.732] Timer tick, asking Buildbucket for the build status
[15:44:08.618] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:08.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:40:0) after 1m0s
[15:45:08.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:40:0)
[15:45:08.656] Timer tick, asking Buildbucket for the build status
[15:45:08.723] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:08.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:41:0) after 1m0s
[15:46:08.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:41:0)
[15:46:08.781] Timer tick, asking Buildbucket for the build status
[15:46:08.960] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:08.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:42:0) after 1m0s
[15:47:08.996] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:42:0)
[15:47:08.996] Timer tick, asking Buildbucket for the build status
[15:47:09.144] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:09.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:43:0) after 1m0s
[15:48:09.196] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:43:0)
[15:48:09.196] Timer tick, asking Buildbucket for the build status
[15:48:09.269] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:09.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:44:0) after 1m0s
[15:49:09.323] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:44:0)
[15:49:09.323] Timer tick, asking Buildbucket for the build status
[15:49:09.392] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:09.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:45:0) after 1m0s
[15:50:09.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:45:0)
[15:50:09.410] Timer tick, asking Buildbucket for the build status
[15:50:09.494] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:09.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:46:0) after 1m0s
[15:51:09.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:46:0)
[15:51:09.547] Timer tick, asking Buildbucket for the build status
[15:51:09.611] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:09.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:47:0) after 1m0s
[15:52:09.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:47:0)
[15:52:09.648] Timer tick, asking Buildbucket for the build status
[15:52:09.885] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:09.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:48:0) after 1m0s
[15:53:09.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:48:0)
[15:53:09.908] Timer tick, asking Buildbucket for the build status
[15:53:10.086] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:10.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:49:0) after 1m0s
[15:54:10.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:49:0)
[15:54:10.157] Timer tick, asking Buildbucket for the build status
[15:54:10.237] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:10.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:50:0) after 1m0s
[15:55:10.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:50:0)
[15:55:10.264] Timer tick, asking Buildbucket for the build status
[15:55:10.333] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:10.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:51:0) after 1m0s
[15:56:10.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:51:0)
[15:56:10.397] Timer tick, asking Buildbucket for the build status
[15:56:10.482] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:10.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:52:0) after 1m0s
[15:57:10.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:52:0)
[15:57:10.545] Timer tick, asking Buildbucket for the build status
[15:57:10.702] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:10.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:53:0) after 1m0s
[15:58:10.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:53:0)
[15:58:10.846] Timer tick, asking Buildbucket for the build status
[15:58:11.028] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:11.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:54:0) after 1m0s
[15:59:11.232] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:54:0)
[15:59:11.232] Timer tick, asking Buildbucket for the build status
[15:59:11.435] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:11.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:55:0) after 1m0s
[16:00:11.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:55:0)
[16:00:11.537] Timer tick, asking Buildbucket for the build status
[16:00:11.666] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:11.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:56:0) after 1m0s
[16:01:11.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:56:0)
[16:01:11.732] Timer tick, asking Buildbucket for the build status
[16:01:11.835] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:11.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:57:0) after 1m0s
[16:02:12.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:57:0)
[16:02:12.029] Timer tick, asking Buildbucket for the build status
[16:02:12.161] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:12.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:58:0) after 1m0s
[16:03:12.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:58:0)
[16:03:12.233] Timer tick, asking Buildbucket for the build status
[16:03:12.311] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:12.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:59:0) after 1m0s
[16:04:12.533] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:59:0)
[16:04:12.533] Timer tick, asking Buildbucket for the build status
[16:04:16.222] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:16.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:60:0) after 1m0s
[16:05:16.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:60:0)
[16:05:16.303] Timer tick, asking Buildbucket for the build status
[16:05:16.458] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:16.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:61:0) after 1m0s
[16:06:16.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:61:0)
[16:06:16.521] Timer tick, asking Buildbucket for the build status
[16:06:16.738] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:16.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:62:0) after 1m0s
[16:07:16.762] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:62:0)
[16:07:16.762] Timer tick, asking Buildbucket for the build status
[16:07:16.964] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:16.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:63:0) after 1m0s
[16:08:16.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:63:0)
[16:08:16.981] Timer tick, asking Buildbucket for the build status
[16:08:17.045] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:17.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:64:0) after 1m0s
[16:09:17.084] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:64:0)
[16:09:17.084] Timer tick, asking Buildbucket for the build status
[16:09:17.149] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:17.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:65:0) after 1m0s
[16:10:17.210] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:65:0)
[16:10:17.210] Timer tick, asking Buildbucket for the build status
[16:10:17.422] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:17.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:66:0) after 1m0s
[16:11:17.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:66:0)
[16:11:17.432] Timer tick, asking Buildbucket for the build status
[16:11:17.497] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:17.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:67:0) after 1m0s
[16:12:17.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:67:0)
[16:12:17.619] Timer tick, asking Buildbucket for the build status
[16:12:17.726] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:17.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:68:0) after 1m0s
[16:13:17.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:68:0)
[16:13:17.727] Timer tick, asking Buildbucket for the build status
[16:13:17.902] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:17.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:69:0) after 1m0s
[16:14:18.061] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:69:0)
[16:14:18.061] Timer tick, asking Buildbucket for the build status
[16:14:18.119] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:18.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:70:0) after 1m0s
[16:15:18.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:70:0)
[16:15:18.167] Timer tick, asking Buildbucket for the build status
[16:15:18.263] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:18.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:71:0) after 1m0s
[16:16:18.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:71:0)
[16:16:18.352] Timer tick, asking Buildbucket for the build status
[16:16:18.423] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:18.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:72:0) after 1m0s
[16:17:18.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:72:0)
[16:17:18.587] Timer tick, asking Buildbucket for the build status
[16:17:18.707] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:18.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:73:0) after 1m0s
[16:18:18.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:73:0)
[16:18:18.851] Timer tick, asking Buildbucket for the build status
[16:18:19.067] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:19.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:74:0) after 1m0s
[16:19:19.181] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:74:0)
[16:19:19.181] Timer tick, asking Buildbucket for the build status
[16:19:19.263] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:19.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:75:0) after 1m0s
[16:20:19.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:75:0)
[16:20:19.369] Timer tick, asking Buildbucket for the build status
[16:20:19.453] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:19.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:76:0) after 1m0s
[16:21:19.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:76:0)
[16:21:19.580] Timer tick, asking Buildbucket for the build status
[16:21:19.846] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:19.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:77:0) after 1m0s
[16:22:19.964] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:77:0)
[16:22:19.964] Timer tick, asking Buildbucket for the build status
[16:22:20.193] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:20.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:78:0) after 1m0s
[16:23:20.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:78:0)
[16:23:20.316] Timer tick, asking Buildbucket for the build status
[16:23:20.379] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:20.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:79:0) after 1m0s
[16:24:20.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:79:0)
[16:24:20.531] Timer tick, asking Buildbucket for the build status
[16:24:20.606] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:20.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:80:0) after 1m0s
[16:25:20.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:80:0)
[16:25:20.653] Timer tick, asking Buildbucket for the build status
[16:25:20.735] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:20.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:81:0) after 1m0s
[16:26:20.771] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:81:0)
[16:26:20.771] Timer tick, asking Buildbucket for the build status
[16:26:20.845] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:20.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:82:0) after 1m0s
[16:27:20.869] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:82:0)
[16:27:20.869] Timer tick, asking Buildbucket for the build status
[16:27:20.970] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:20.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:83:0) after 1m0s
[16:28:21.002] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:83:0)
[16:28:21.002] Timer tick, asking Buildbucket for the build status
[16:28:21.073] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:21.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:84:0) after 1m0s
[16:29:21.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:84:0)
[16:29:21.460] Timer tick, asking Buildbucket for the build status
[16:29:21.533] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:21.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:85:0) after 1m0s
[16:30:21.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:85:0)
[16:30:21.632] Timer tick, asking Buildbucket for the build status
[16:30:21.711] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:21.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:86:0) after 1m0s
[16:31:21.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:86:0)
[16:31:21.880] Timer tick, asking Buildbucket for the build status
[16:31:21.939] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:21.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:87:0) after 1m0s
[16:32:22.252] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:87:0)
[16:32:22.252] Timer tick, asking Buildbucket for the build status
[16:32:22.454] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:22.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:88:0) after 1m0s
[16:33:23.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:88:0)
[16:33:23.458] Timer tick, asking Buildbucket for the build status
[16:33:23.532] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:23.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:89:0) after 1m0s
[16:34:24.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:89:0)
[16:34:24.386] Timer tick, asking Buildbucket for the build status
[16:34:24.458] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:24.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:90:0) after 1m0s
[16:35:24.489] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:90:0)
[16:35:24.489] Timer tick, asking Buildbucket for the build status
[16:35:24.675] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:24.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:91:0) after 1m0s
[16:36:24.752] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:91:0)
[16:36:24.752] Timer tick, asking Buildbucket for the build status
[16:36:25.903] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:25.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:92:0) after 1m0s
[16:37:25.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:92:0)
[16:37:25.994] Timer tick, asking Buildbucket for the build status
[16:37:26.134] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:26.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:93:0) after 1m0s
[16:38:26.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:93:0)
[16:38:26.233] Timer tick, asking Buildbucket for the build status
[16:38:26.320] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:26.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:94:0) after 1m0s
[16:39:26.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:94:0)
[16:39:26.362] Timer tick, asking Buildbucket for the build status
[16:39:26.642] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:26.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:95:0) after 1m0s
[16:40:26.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:95:0)
[16:40:26.676] Timer tick, asking Buildbucket for the build status
[16:40:26.811] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:26.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:96:0) after 1m0s
[16:41:26.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:96:0)
[16:41:26.899] Timer tick, asking Buildbucket for the build status
[16:41:27.049] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:27.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:97:0) after 1m0s
[16:42:27.098] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:97:0)
[16:42:27.098] Timer tick, asking Buildbucket for the build status
[16:42:27.262] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:27.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:98:0) after 1m0s
[16:43:27.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:98:0)
[16:43:27.288] Timer tick, asking Buildbucket for the build status
[16:43:27.519] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:27.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:99:0) after 1m0s
[16:44:27.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:99:0)
[16:44:27.569] Timer tick, asking Buildbucket for the build status
[16:44:27.629] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:27.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:100:0) after 1m0s
[16:45:27.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:100:0)
[16:45:27.673] Timer tick, asking Buildbucket for the build status
[16:45:28.101] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:28.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:101:0) after 1m0s
[16:46:28.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:101:0)
[16:46:28.130] Timer tick, asking Buildbucket for the build status
[16:46:28.206] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:28.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:102:0) after 1m0s
[16:47:28.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:102:0)
[16:47:28.270] Timer tick, asking Buildbucket for the build status
[16:47:28.395] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:28.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:103:0) after 1m0s
[16:48:28.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:103:0)
[16:48:28.423] Timer tick, asking Buildbucket for the build status
[16:48:28.477] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:28.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:104:0) after 1m0s
[16:49:28.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:104:0)
[16:49:28.657] Timer tick, asking Buildbucket for the build status
[16:49:28.733] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:28.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:105:0) after 1m0s
[16:50:28.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:105:0)
[16:50:28.772] Timer tick, asking Buildbucket for the build status
[16:50:28.836] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:28.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:106:0) after 1m0s
[16:51:28.874] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:106:0)
[16:51:28.874] Timer tick, asking Buildbucket for the build status
[16:51:28.969] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:28.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:107:0) after 1m0s
[16:52:29.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:107:0)
[16:52:29.033] Timer tick, asking Buildbucket for the build status
[16:52:29.142] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:29.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:108:0) after 1m0s
[16:53:29.200] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:108:0)
[16:53:29.200] Timer tick, asking Buildbucket for the build status
[16:53:29.337] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:29.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:109:0) after 1m0s
[16:54:29.351] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:109:0)
[16:54:29.351] Timer tick, asking Buildbucket for the build status
[16:54:29.904] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:29.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:110:0) after 1m0s
[16:55:29.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:110:0)
[16:55:29.981] Timer tick, asking Buildbucket for the build status
[16:55:30.077] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:30.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:111:0) after 1m0s
[16:56:30.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:111:0)
[16:56:30.094] Timer tick, asking Buildbucket for the build status
[16:56:30.181] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:30.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:112:0) after 1m0s
[16:57:30.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:112:0)
[16:57:30.236] Timer tick, asking Buildbucket for the build status
[16:57:30.356] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:30.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:113:0) after 1m0s
[16:58:30.495] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:113:0)
[16:58:30.495] Timer tick, asking Buildbucket for the build status
[16:58:30.931] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:30.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:114:0) after 1m0s
[16:59:30.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:114:0)
[16:59:30.958] Timer tick, asking Buildbucket for the build status
[16:59:31.434] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:31.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:115:0) after 1m0s
[17:00:31.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:115:0)
[17:00:31.465] Timer tick, asking Buildbucket for the build status
[17:00:31.737] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:31.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:116:0) after 1m0s
[17:01:31.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:116:0)
[17:01:31.938] Timer tick, asking Buildbucket for the build status
[17:01:32.119] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:32.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:117:0) after 1m0s
[17:02:32.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:117:0)
[17:02:32.519] Timer tick, asking Buildbucket for the build status
[17:02:32.686] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:32.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:118:0) after 1m0s
[17:03:32.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:118:0)
[17:03:32.823] Timer tick, asking Buildbucket for the build status
[17:03:33.172] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:33.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:119:0) after 1m0s
[17:04:33.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:119:0)
[17:04:33.235] Timer tick, asking Buildbucket for the build status
[17:04:33.313] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:33.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:120:0) after 1m0s
[17:05:33.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:120:0)
[17:05:33.348] Timer tick, asking Buildbucket for the build status
[17:05:33.428] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:33.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:121:0) after 1m0s
[17:06:33.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:121:0)
[17:06:33.469] Timer tick, asking Buildbucket for the build status
[17:06:33.539] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:33.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:122:0) after 1m0s
[17:07:33.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:122:0)
[17:07:33.567] Timer tick, asking Buildbucket for the build status
[17:07:33.780] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:33.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:123:0) after 1m0s
[17:08:33.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:123:0)
[17:08:33.855] Timer tick, asking Buildbucket for the build status
[17:08:33.918] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:33.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:124:0) after 1m0s
[17:09:33.992] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:124:0)
[17:09:33.992] Timer tick, asking Buildbucket for the build status
[17:09:34.087] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:34.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:125:0) after 1m0s
[17:10:34.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:125:0)
[17:10:34.147] Timer tick, asking Buildbucket for the build status
[17:10:34.226] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:34.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:126:0) after 1m0s
[17:11:34.308] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:126:0)
[17:11:34.308] Timer tick, asking Buildbucket for the build status
[17:11:34.387] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:34.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:127:0) after 1m0s
[17:12:34.436] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:127:0)
[17:12:34.436] Timer tick, asking Buildbucket for the build status
[17:12:34.783] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:34.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:128:0) after 1m0s
[17:13:34.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:128:0)
[17:13:34.836] Timer tick, asking Buildbucket for the build status
[17:13:35.041] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:35.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:129:0) after 1m0s
[17:14:35.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:129:0)
[17:14:35.140] Timer tick, asking Buildbucket for the build status
[17:14:35.366] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:35.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:130:0) after 1m0s
[17:15:35.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:130:0)
[17:15:35.394] Timer tick, asking Buildbucket for the build status
[17:15:35.471] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:35.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:131:0) after 1m0s
[17:16:35.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:131:0)
[17:16:35.554] Timer tick, asking Buildbucket for the build status
[17:16:35.610] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:35.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:132:0) after 1m0s
[17:17:35.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:132:0)
[17:17:35.781] Timer tick, asking Buildbucket for the build status
[17:17:36.103] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:36.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:133:0) after 1m0s
[17:18:36.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:133:0)
[17:18:36.440] Timer tick, asking Buildbucket for the build status
[17:18:36.543] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:36.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:134:0) after 1m0s
[17:19:36.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:134:0)
[17:19:36.620] Timer tick, asking Buildbucket for the build status
[17:19:36.687] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:36.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:135:0) after 1m0s
[17:20:36.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:135:0)
[17:20:36.741] Timer tick, asking Buildbucket for the build status
[17:20:36.926] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:36.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:136:0) after 1m0s
[17:21:37.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:136:0)
[17:21:37.037] Timer tick, asking Buildbucket for the build status
[17:21:37.362] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:37.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:137:0) after 1m0s
[17:22:37.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:137:0)
[17:22:37.475] Timer tick, asking Buildbucket for the build status
[17:22:37.621] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:37.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:138:0) after 1m0s
[17:23:37.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:138:0)
[17:23:37.666] Timer tick, asking Buildbucket for the build status
[17:23:37.741] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:37.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:139:0) after 1m0s
[17:24:37.753] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:139:0)
[17:24:37.753] Timer tick, asking Buildbucket for the build status
[17:24:37.818] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:37.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:140:0) after 1m0s
[17:25:38.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:140:0)
[17:25:38.012] Timer tick, asking Buildbucket for the build status
[17:25:38.205] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:38.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:141:0) after 1m0s
[17:26:38.360] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:141:0)
[17:26:38.360] Timer tick, asking Buildbucket for the build status
[17:26:38.632] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:38.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:142:0) after 1m0s
[17:27:38.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:142:0)
[17:27:38.768] Timer tick, asking Buildbucket for the build status
[17:27:38.855] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:38.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:143:0) after 1m0s
[17:28:39.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:143:0)
[17:28:39.053] Timer tick, asking Buildbucket for the build status
[17:28:39.119] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:39.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:144:0) after 1m0s
[17:29:39.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:144:0)
[17:29:39.152] Timer tick, asking Buildbucket for the build status
[17:29:39.325] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:39.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:145:0) after 1m0s
[17:30:40.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:145:0)
[17:30:40.470] Timer tick, asking Buildbucket for the build status
[17:30:40.611] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:40.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:146:0) after 1m0s
[17:31:40.749] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:146:0)
[17:31:40.749] Timer tick, asking Buildbucket for the build status
[17:31:40.819] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:40.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:147:0) after 1m0s
[17:32:41.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:147:0)
[17:32:41.012] Timer tick, asking Buildbucket for the build status
[17:32:41.186] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:41.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:148:0) after 1m0s
[17:33:41.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:148:0)
[17:33:41.599] Timer tick, asking Buildbucket for the build status
[17:33:41.694] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:41.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:149:0) after 1m0s
[17:34:41.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:149:0)
[17:34:41.836] Timer tick, asking Buildbucket for the build status
[17:34:41.910] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:41.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:150:0) after 1m0s
[17:35:42.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:150:0)
[17:35:42.017] Timer tick, asking Buildbucket for the build status
[17:35:42.085] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:42.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:151:0) after 1m0s
[17:36:42.297] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:151:0)
[17:36:42.297] Timer tick, asking Buildbucket for the build status
[17:36:42.437] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:42.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:152:0) after 1m0s
[17:37:42.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:152:0)
[17:37:42.515] Timer tick, asking Buildbucket for the build status
[17:37:42.611] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:42.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:153:0) after 1m0s
[17:38:42.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:153:0)
[17:38:42.721] Timer tick, asking Buildbucket for the build status
[17:38:42.821] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:42.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:154:0) after 1m0s
[17:39:42.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:154:0)
[17:39:42.906] Timer tick, asking Buildbucket for the build status
[17:39:42.968] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:42.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:155:0) after 1m0s
[17:40:43.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:155:0)
[17:40:43.033] Timer tick, asking Buildbucket for the build status
[17:40:43.099] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:43.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:156:0) after 1m0s
[17:41:43.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:156:0)
[17:41:43.178] Timer tick, asking Buildbucket for the build status
[17:41:43.667] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:43.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:157:0) after 1m0s
[17:42:43.679] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:157:0)
[17:42:43.679] Timer tick, asking Buildbucket for the build status
[17:42:43.891] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:43.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:158:0) after 1m0s
[17:43:44.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:158:0)
[17:43:44.005] Timer tick, asking Buildbucket for the build status
[17:43:44.133] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:44.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:159:0) after 1m0s
[17:44:44.190] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:159:0)
[17:44:44.190] Timer tick, asking Buildbucket for the build status
[17:44:44.253] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:44.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:160:0) after 1m0s
[17:45:44.353] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:160:0)
[17:45:44.353] Timer tick, asking Buildbucket for the build status
[17:45:44.615] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:44.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:161:0) after 1m0s
[17:46:44.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:161:0)
[17:46:44.642] Timer tick, asking Buildbucket for the build status
[17:46:44.743] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:44.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:162:0) after 1m0s
[17:47:44.753] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:162:0)
[17:47:44.753] Timer tick, asking Buildbucket for the build status
[17:47:44.980] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:44.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:163:0) after 1m0s
[17:48:45.091] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:163:0)
[17:48:45.091] Timer tick, asking Buildbucket for the build status
[17:48:45.335] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:45.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:164:0) after 1m0s
[17:49:45.456] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:164:0)
[17:49:45.456] Timer tick, asking Buildbucket for the build status
[17:49:45.537] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:45.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:165:0) after 1m0s
[17:50:45.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:165:0)
[17:50:45.636] Timer tick, asking Buildbucket for the build status
[17:50:45.800] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:45.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:166:0) after 1m0s
[17:51:45.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:166:0)
[17:51:45.828] Timer tick, asking Buildbucket for the build status
[17:51:45.946] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:45.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:167:0) after 1m0s
[17:52:45.977] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:167:0)
[17:52:45.977] Timer tick, asking Buildbucket for the build status
[17:52:46.089] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:46.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:168:0) after 1m0s
[17:53:46.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:168:0)
[17:53:46.169] Timer tick, asking Buildbucket for the build status
[17:53:46.436] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:46.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:169:0) after 1m0s
[17:54:46.711] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:169:0)
[17:54:46.711] Timer tick, asking Buildbucket for the build status
[17:54:46.997] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:46.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:170:0) after 1m0s
[17:55:47.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:170:0)
[17:55:47.241] Timer tick, asking Buildbucket for the build status
[17:55:47.326] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:47.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:171:0) after 1m0s
[17:56:47.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:171:0)
[17:56:47.411] Timer tick, asking Buildbucket for the build status
[17:56:47.487] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:47.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:172:0) after 1m0s
[17:57:47.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:172:0)
[17:57:47.667] Timer tick, asking Buildbucket for the build status
[17:57:47.766] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:47.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:173:0) after 1m0s
[17:58:47.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:173:0)
[17:58:47.803] Timer tick, asking Buildbucket for the build status
[17:58:48.136] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:48.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:174:0) after 1m0s
[17:59:48.212] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:174:0)
[17:59:48.212] Timer tick, asking Buildbucket for the build status
[17:59:48.305] Build 8858028008498750128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:48.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023458070687473632:175:0) after 1m0s
[18:00:35.913] Received PubSub notification, asking Buildbucket for the build status
[18:00:36.304] Build 8858028008498750128: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:00:36.304] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610733634962684",
"created_by": "project:chromium",
"created_ts": "1610723216495054",
"failure_reason": "BUILD_FAILURE",
"id": "8858028008498750128",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"749006de9966fb0229f7361a12455728bbd67bfc\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[86641/88135] LINK weblayer_browsertests.exe weblayer_browsertests.exe.pdb\\nFAILED: weblayer_browsertests.exe weblayer_browsertests.exe.pdb\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"749006de9966fb0229f7361a12455728bbd67bfc\", \"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-0-j1at\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1392, \"got_angle_revision\": \"5678b56f5e2129615193034cd90bb6a0ef3c70d3\", \"got_dawn_revision\": \"5a6fda1a6fcc99553bab1411e2159cf962b6a36d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"749006de9966fb0229f7361a12455728bbd67bfc\", \"got_revision_cp\": \"refs/heads/master@{#844070}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"aedd133cd85f40f92c304776d240a8a3503d1d71\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"749006de9966fb0229f7361a12455728bbd67bfc\"}, \"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-0-j1at\"], \"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\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[86641/88135] LINK weblayer_browsertests.exe weblayer_browsertests.exe.pdb\\nFAILED: weblayer_browsertests.exe weblayer_browsertests.exe.pdb\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610723259325398",
"status": "COMPLETED",
"status_changed_ts": "1610723259732953",
"tags": [
"build_address:luci.chromium.ci/win-official/1392",
"builder:win-official",
"buildset:commit/git/749006de9966fb0229f7361a12455728bbd67bfc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/749006de9966fb0229f7361a12455728bbd67bfc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023458070687473632",
"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/8858028008498750128/+/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:511f675c454ac810",
"user_agent:luci-scheduler"
],
"updated_ts": "1610733635568630",
"url": "https://ci.chromium.org/b/8858028008498750128",
"utcnow_ts": "1610733636273289"
}
}
[18:00:36.304] Invocation finished in 2h53m41.280180917s with status FAILED