[04:48:56.711] New invocation is queued and will start shortly
[04:48:57.777] Starting the invocation (attempt 1)
[04:48:57.830] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:48:57.830] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:48:57.830] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027936201487805168",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a67dba4e985a7119c004d8a142394839f4c5887a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9027936201487805168",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/a67dba4e985a7119c004d8a142394839f4c5887a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a67dba4e985a7119c004d8a142394839f4c5887a",
"gitiles_ref:refs/heads/master"
]
}
[04:48:58.614] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606452537934900",
"id": "8862506139541140720",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a67dba4e985a7119c004d8a142394839f4c5887a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606452538398822",
"tags": [
"build_address:luci.chromium.ci/win-official/1081",
"builder:win-official",
"buildset:commit/git/a67dba4e985a7119c004d8a142394839f4c5887a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a67dba4e985a7119c004d8a142394839f4c5887a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027936201487805168",
"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/8862506139541140720/+/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": "1606452538398942",
"url": "https://ci.chromium.org/b/8862506139541140720",
"utcnow_ts": "1606452538599131"
}
}
[04:48:58.614] Task URL: https://ci.chromium.org/b/8862506139541140720
[04:48:58.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:2:0) after 1m0s
[04:49:58.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:2:0)
[04:49:58.633] Timer tick, asking Buildbucket for the build status
[04:49:58.812] Build 8862506139541140720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:49:58.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:3:0) after 1m0s
[04:50:58.914] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:3:0)
[04:50:58.914] Timer tick, asking Buildbucket for the build status
[04:50:59.272] Build 8862506139541140720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:50:59.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:4:0) after 1m0s
[04:51:59.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:4:0)
[04:51:59.425] Timer tick, asking Buildbucket for the build status
[04:51:59.705] Build 8862506139541140720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:51:59.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:5:0) after 1m0s
[04:52:28.664] Received PubSub notification, asking Buildbucket for the build status
[04:52:28.769] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:59.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:5:0)
[04:52:59.750] Timer tick, asking Buildbucket for the build status
[04:52:59.879] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:59.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:7:0) after 1m0s
[04:53:59.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:7:0)
[04:53:59.871] Timer tick, asking Buildbucket for the build status
[04:53:59.985] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:59.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:8:0) after 1m0s
[04:55:00.011] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:8:0)
[04:55:00.011] Timer tick, asking Buildbucket for the build status
[04:55:00.319] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:00.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:9:0) after 1m0s
[04:56:00.967] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:9:0)
[04:56:00.967] Timer tick, asking Buildbucket for the build status
[04:56:01.240] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:01.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:10:0) after 1m0s
[04:57:02.370] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:10:0)
[04:57:02.370] Timer tick, asking Buildbucket for the build status
[04:57:02.586] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:02.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:11:0) after 1m0s
[04:58:02.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:11:0)
[04:58:02.639] Timer tick, asking Buildbucket for the build status
[04:58:02.739] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:02.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:12:0) after 1m0s
[04:59:02.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:12:0)
[04:59:02.833] Timer tick, asking Buildbucket for the build status
[04:59:02.952] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:02.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:13:0) after 1m0s
[05:00:02.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:13:0)
[05:00:02.976] Timer tick, asking Buildbucket for the build status
[05:00:03.066] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:03.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:14:0) after 1m0s
[05:01:03.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:14:0)
[05:01:03.100] Timer tick, asking Buildbucket for the build status
[05:01:03.683] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:03.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:15:0) after 1m0s
[05:02:03.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:15:0)
[05:02:03.746] Timer tick, asking Buildbucket for the build status
[05:02:03.860] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:03.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:16:0) after 1m0s
[05:03:03.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:16:0)
[05:03:03.887] Timer tick, asking Buildbucket for the build status
[05:03:04.097] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:04.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:17:0) after 1m0s
[05:04:04.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:17:0)
[05:04:04.105] Timer tick, asking Buildbucket for the build status
[05:04:04.360] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:04.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:18:0) after 1m0s
[05:05:04.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:18:0)
[05:05:04.430] Timer tick, asking Buildbucket for the build status
[05:05:04.510] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:04.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:19:0) after 1m0s
[05:06:04.578] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:19:0)
[05:06:04.578] Timer tick, asking Buildbucket for the build status
[05:06:04.732] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:04.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:20:0) after 1m0s
[05:07:04.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:20:0)
[05:07:04.758] Timer tick, asking Buildbucket for the build status
[05:07:04.829] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:04.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:21:0) after 1m0s
[05:08:04.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:21:0)
[05:08:04.855] Timer tick, asking Buildbucket for the build status
[05:08:05.003] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:05.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:22:0) after 1m0s
[05:09:04.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:22:0)
[05:09:04.983] Timer tick, asking Buildbucket for the build status
[05:09:05.100] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:05.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:23:0) after 1m0s
[05:10:05.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:23:0)
[05:10:05.177] Timer tick, asking Buildbucket for the build status
[05:10:05.677] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:05.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:24:0) after 1m0s
[05:11:05.702] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:24:0)
[05:11:05.702] Timer tick, asking Buildbucket for the build status
[05:11:06.063] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:06.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:25:0) after 1m0s
[05:12:06.081] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:25:0)
[05:12:06.081] Timer tick, asking Buildbucket for the build status
[05:12:06.293] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:06.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:26:0) after 1m0s
[05:13:06.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:26:0)
[05:13:06.367] Timer tick, asking Buildbucket for the build status
[05:13:06.528] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:06.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:27:0) after 1m0s
[05:14:06.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:27:0)
[05:14:06.534] Timer tick, asking Buildbucket for the build status
[05:14:06.628] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:06.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:28:0) after 1m0s
[05:15:06.681] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:28:0)
[05:15:06.681] Timer tick, asking Buildbucket for the build status
[05:15:06.794] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:06.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:29:0) after 1m0s
[05:16:06.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:29:0)
[05:16:06.837] Timer tick, asking Buildbucket for the build status
[05:16:06.904] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:06.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:30:0) after 1m0s
[05:17:06.953] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:30:0)
[05:17:06.953] Timer tick, asking Buildbucket for the build status
[05:17:07.244] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:07.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:31:0) after 1m0s
[05:18:07.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:31:0)
[05:18:07.274] Timer tick, asking Buildbucket for the build status
[05:18:07.393] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:07.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:32:0) after 1m0s
[05:19:07.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:32:0)
[05:19:07.422] Timer tick, asking Buildbucket for the build status
[05:19:07.666] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:07.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:33:0) after 1m0s
[05:20:07.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:33:0)
[05:20:07.696] Timer tick, asking Buildbucket for the build status
[05:20:07.847] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:07.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:34:0) after 1m0s
[05:21:08.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:34:0)
[05:21:08.132] Timer tick, asking Buildbucket for the build status
[05:21:08.242] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:08.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:35:0) after 1m0s
[05:22:08.426] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:35:0)
[05:22:08.426] Timer tick, asking Buildbucket for the build status
[05:22:08.591] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:08.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:36:0) after 1m0s
[05:23:08.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:36:0)
[05:23:08.708] Timer tick, asking Buildbucket for the build status
[05:23:08.808] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:08.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:37:0) after 1m0s
[05:24:09.351] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:37:0)
[05:24:09.351] Timer tick, asking Buildbucket for the build status
[05:24:09.528] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:09.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:38:0) after 1m0s
[05:25:09.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:38:0)
[05:25:09.602] Timer tick, asking Buildbucket for the build status
[05:25:09.676] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:09.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:39:0) after 1m0s
[05:26:09.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:39:0)
[05:26:09.805] Timer tick, asking Buildbucket for the build status
[05:26:10.055] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:10.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:40:0) after 1m0s
[05:27:10.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:40:0)
[05:27:10.199] Timer tick, asking Buildbucket for the build status
[05:27:10.713] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:10.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:41:0) after 1m0s
[05:28:10.831] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:41:0)
[05:28:10.831] Timer tick, asking Buildbucket for the build status
[05:28:11.011] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:11.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:42:0) after 1m0s
[05:29:11.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:42:0)
[05:29:11.302] Timer tick, asking Buildbucket for the build status
[05:29:11.458] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:11.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:43:0) after 1m0s
[05:30:11.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:43:0)
[05:30:11.555] Timer tick, asking Buildbucket for the build status
[05:30:11.631] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:11.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:44:0) after 1m0s
[05:31:11.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:44:0)
[05:31:11.731] Timer tick, asking Buildbucket for the build status
[05:31:11.945] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:11.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:45:0) after 1m0s
[05:32:11.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:45:0)
[05:32:11.925] Timer tick, asking Buildbucket for the build status
[05:32:12.073] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:12.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:46:0) after 1m0s
[05:33:12.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:46:0)
[05:33:12.159] Timer tick, asking Buildbucket for the build status
[05:33:12.264] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:12.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:47:0) after 1m0s
[05:34:12.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:47:0)
[05:34:12.327] Timer tick, asking Buildbucket for the build status
[05:34:12.686] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:12.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:48:0) after 1m0s
[05:35:12.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:48:0)
[05:35:12.741] Timer tick, asking Buildbucket for the build status
[05:35:12.911] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:12.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:49:0) after 1m0s
[05:36:12.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:49:0)
[05:36:12.976] Timer tick, asking Buildbucket for the build status
[05:36:13.082] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:13.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:50:0) after 1m0s
[05:37:13.297] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:50:0)
[05:37:13.297] Timer tick, asking Buildbucket for the build status
[05:37:13.500] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:13.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:51:0) after 1m0s
[05:38:13.711] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:51:0)
[05:38:13.711] Timer tick, asking Buildbucket for the build status
[05:38:15.049] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:15.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:52:0) after 1m0s
[05:39:15.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:52:0)
[05:39:15.077] Timer tick, asking Buildbucket for the build status
[05:39:15.189] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:15.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:53:0) after 1m0s
[05:40:15.212] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:53:0)
[05:40:15.212] Timer tick, asking Buildbucket for the build status
[05:40:15.341] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:15.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:54:0) after 1m0s
[05:41:15.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:54:0)
[05:41:15.404] Timer tick, asking Buildbucket for the build status
[05:41:15.499] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:15.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:55:0) after 1m0s
[05:42:15.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:55:0)
[05:42:15.699] Timer tick, asking Buildbucket for the build status
[05:42:15.768] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:15.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:56:0) after 1m0s
[05:43:15.966] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:56:0)
[05:43:15.966] Timer tick, asking Buildbucket for the build status
[05:43:16.113] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:16.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:57:0) after 1m0s
[05:44:16.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:57:0)
[05:44:16.130] Timer tick, asking Buildbucket for the build status
[05:44:16.358] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:16.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:58:0) after 1m0s
[05:45:16.370] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:58:0)
[05:45:16.370] Timer tick, asking Buildbucket for the build status
[05:45:16.545] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:16.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:59:0) after 1m0s
[05:46:16.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:59:0)
[05:46:16.600] Timer tick, asking Buildbucket for the build status
[05:46:16.743] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:16.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:60:0) after 1m0s
[05:47:16.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:60:0)
[05:47:16.780] Timer tick, asking Buildbucket for the build status
[05:47:16.838] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:16.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:61:0) after 1m0s
[05:48:16.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:61:0)
[05:48:16.894] Timer tick, asking Buildbucket for the build status
[05:48:16.955] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:16.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:62:0) after 1m0s
[05:49:16.992] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:62:0)
[05:49:16.992] Timer tick, asking Buildbucket for the build status
[05:49:17.154] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:17.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:63:0) after 1m0s
[05:50:17.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:63:0)
[05:50:17.174] Timer tick, asking Buildbucket for the build status
[05:50:17.263] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:17.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:64:0) after 1m0s
[05:51:17.291] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:64:0)
[05:51:17.291] Timer tick, asking Buildbucket for the build status
[05:51:17.365] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:17.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:65:0) after 1m0s
[05:52:17.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:65:0)
[05:52:17.425] Timer tick, asking Buildbucket for the build status
[05:52:17.490] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:17.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:66:0) after 1m0s
[05:53:17.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:66:0)
[05:53:17.505] Timer tick, asking Buildbucket for the build status
[05:53:17.577] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:17.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:67:0) after 1m0s
[05:54:17.566] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:67:0)
[05:54:17.566] Timer tick, asking Buildbucket for the build status
[05:54:17.659] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:17.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:68:0) after 1m0s
[05:55:17.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:68:0)
[05:55:17.688] Timer tick, asking Buildbucket for the build status
[05:55:17.750] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:17.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:69:0) after 1m0s
[05:56:17.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:69:0)
[05:56:17.786] Timer tick, asking Buildbucket for the build status
[05:56:17.861] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:17.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:70:0) after 1m0s
[05:57:17.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:70:0)
[05:57:17.941] Timer tick, asking Buildbucket for the build status
[05:57:18.013] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:18.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:71:0) after 1m0s
[05:58:18.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:71:0)
[05:58:18.100] Timer tick, asking Buildbucket for the build status
[05:58:18.181] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:18.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:72:0) after 1m0s
[05:59:18.203] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:72:0)
[05:59:18.203] Timer tick, asking Buildbucket for the build status
[05:59:18.303] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:18.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:73:0) after 1m0s
[06:00:18.334] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:73:0)
[06:00:18.334] Timer tick, asking Buildbucket for the build status
[06:00:18.602] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:18.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:74:0) after 1m0s
[06:01:25.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:74:0)
[06:01:25.593] Timer tick, asking Buildbucket for the build status
[06:01:25.666] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:25.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:75:0) after 1m0s
[06:02:25.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:75:0)
[06:02:25.763] Timer tick, asking Buildbucket for the build status
[06:02:25.829] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:25.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:76:0) after 1m0s
[06:03:25.895] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:76:0)
[06:03:25.895] Timer tick, asking Buildbucket for the build status
[06:03:26.093] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:26.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:77:0) after 1m0s
[06:04:26.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:77:0)
[06:04:26.272] Timer tick, asking Buildbucket for the build status
[06:04:26.382] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:26.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:78:0) after 1m0s
[06:05:26.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:78:0)
[06:05:26.563] Timer tick, asking Buildbucket for the build status
[06:05:26.637] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:26.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:79:0) after 1m0s
[06:06:27.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:79:0)
[06:06:27.022] Timer tick, asking Buildbucket for the build status
[06:06:27.101] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:27.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:80:0) after 1m0s
[06:07:27.351] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:80:0)
[06:07:27.351] Timer tick, asking Buildbucket for the build status
[06:07:27.477] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:27.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:81:0) after 1m0s
[06:08:27.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:81:0)
[06:08:27.678] Timer tick, asking Buildbucket for the build status
[06:08:27.787] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:27.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:82:0) after 1m0s
[06:09:27.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:82:0)
[06:09:27.805] Timer tick, asking Buildbucket for the build status
[06:09:28.153] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:28.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:83:0) after 1m0s
[06:10:28.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:83:0)
[06:10:28.298] Timer tick, asking Buildbucket for the build status
[06:10:28.384] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:28.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:84:0) after 1m0s
[06:11:28.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:84:0)
[06:11:28.484] Timer tick, asking Buildbucket for the build status
[06:11:28.539] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:28.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:85:0) after 1m0s
[06:12:28.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:85:0)
[06:12:28.603] Timer tick, asking Buildbucket for the build status
[06:12:28.731] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:28.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:86:0) after 1m0s
[06:13:28.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:86:0)
[06:13:28.847] Timer tick, asking Buildbucket for the build status
[06:13:28.980] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:28.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:87:0) after 1m0s
[06:14:29.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:87:0)
[06:14:29.056] Timer tick, asking Buildbucket for the build status
[06:14:29.424] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:29.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:88:0) after 1m0s
[06:15:29.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:88:0)
[06:15:29.482] Timer tick, asking Buildbucket for the build status
[06:15:29.593] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:29.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:89:0) after 1m0s
[06:16:29.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:89:0)
[06:16:29.657] Timer tick, asking Buildbucket for the build status
[06:16:30.028] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:30.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:90:0) after 1m0s
[06:17:30.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:90:0)
[06:17:30.155] Timer tick, asking Buildbucket for the build status
[06:17:30.216] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:30.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:91:0) after 1m0s
[06:18:30.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:91:0)
[06:18:30.394] Timer tick, asking Buildbucket for the build status
[06:18:30.554] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:30.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:92:0) after 1m0s
[06:19:30.706] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:92:0)
[06:19:30.706] Timer tick, asking Buildbucket for the build status
[06:19:31.018] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:31.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:93:0) after 1m0s
[06:20:31.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:93:0)
[06:20:31.761] Timer tick, asking Buildbucket for the build status
[06:20:32.207] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:32.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:94:0) after 1m0s
[06:21:32.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:94:0)
[06:21:32.499] Timer tick, asking Buildbucket for the build status
[06:21:32.932] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:32.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:95:0) after 1m0s
[06:22:33.351] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:95:0)
[06:22:33.351] Timer tick, asking Buildbucket for the build status
[06:22:33.531] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:33.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:96:0) after 1m0s
[06:23:33.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:96:0)
[06:23:33.735] Timer tick, asking Buildbucket for the build status
[06:23:33.877] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:33.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:97:0) after 1m0s
[06:24:33.928] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:97:0)
[06:24:33.928] Timer tick, asking Buildbucket for the build status
[06:24:34.040] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:34.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:98:0) after 1m0s
[06:25:34.103] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:98:0)
[06:25:34.103] Timer tick, asking Buildbucket for the build status
[06:25:34.201] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:34.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:99:0) after 1m0s
[06:26:34.276] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:99:0)
[06:26:34.276] Timer tick, asking Buildbucket for the build status
[06:26:34.538] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:34.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:100:0) after 1m0s
[06:27:34.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:100:0)
[06:27:34.598] Timer tick, asking Buildbucket for the build status
[06:27:34.657] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:34.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:101:0) after 1m0s
[06:28:34.679] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:101:0)
[06:28:34.679] Timer tick, asking Buildbucket for the build status
[06:28:36.241] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:36.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:102:0) after 1m0s
[06:29:36.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:102:0)
[06:29:36.298] Timer tick, asking Buildbucket for the build status
[06:29:36.406] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:36.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:103:0) after 1m0s
[06:30:36.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:103:0)
[06:30:36.467] Timer tick, asking Buildbucket for the build status
[06:30:36.567] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:36.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:104:0) after 1m0s
[06:31:36.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:104:0)
[06:31:36.594] Timer tick, asking Buildbucket for the build status
[06:31:36.656] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:36.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:105:0) after 1m0s
[06:32:36.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:105:0)
[06:32:36.682] Timer tick, asking Buildbucket for the build status
[06:32:36.777] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:36.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:106:0) after 1m0s
[06:33:36.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:106:0)
[06:33:36.822] Timer tick, asking Buildbucket for the build status
[06:33:36.894] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:36.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:107:0) after 1m0s
[06:34:37.085] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:107:0)
[06:34:37.085] Timer tick, asking Buildbucket for the build status
[06:34:37.861] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:37.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:108:0) after 1m0s
[06:35:37.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:108:0)
[06:35:37.890] Timer tick, asking Buildbucket for the build status
[06:35:37.964] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:37.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:109:0) after 1m0s
[06:36:38.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:109:0)
[06:36:38.131] Timer tick, asking Buildbucket for the build status
[06:36:38.205] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:38.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:110:0) after 1m0s
[06:37:38.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:110:0)
[06:37:38.243] Timer tick, asking Buildbucket for the build status
[06:37:38.297] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:38.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:111:0) after 1m0s
[06:38:38.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:111:0)
[06:38:38.340] Timer tick, asking Buildbucket for the build status
[06:38:38.403] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:38.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:112:0) after 1m0s
[06:39:38.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:112:0)
[06:39:38.484] Timer tick, asking Buildbucket for the build status
[06:39:38.596] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:38.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:113:0) after 1m0s
[06:40:38.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:113:0)
[06:40:38.623] Timer tick, asking Buildbucket for the build status
[06:40:38.691] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:38.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:114:0) after 1m0s
[06:41:38.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:114:0)
[06:41:38.720] Timer tick, asking Buildbucket for the build status
[06:41:38.808] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:38.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:115:0) after 1m0s
[06:42:38.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:115:0)
[06:42:38.834] Timer tick, asking Buildbucket for the build status
[06:42:38.894] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:38.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:116:0) after 1m0s
[06:43:38.966] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:116:0)
[06:43:38.966] Timer tick, asking Buildbucket for the build status
[06:43:39.143] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:39.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:117:0) after 1m0s
[06:44:39.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:117:0)
[06:44:39.249] Timer tick, asking Buildbucket for the build status
[06:44:39.352] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:39.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:118:0) after 1m0s
[06:45:39.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:118:0)
[06:45:39.442] Timer tick, asking Buildbucket for the build status
[06:45:39.515] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:39.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:119:0) after 1m0s
[06:46:39.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:119:0)
[06:46:39.596] Timer tick, asking Buildbucket for the build status
[06:46:39.668] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:39.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:120:0) after 1m0s
[06:47:39.767] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:120:0)
[06:47:39.767] Timer tick, asking Buildbucket for the build status
[06:47:40.038] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:40.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:121:0) after 1m0s
[06:48:40.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:121:0)
[06:48:40.069] Timer tick, asking Buildbucket for the build status
[06:48:40.384] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:40.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:122:0) after 1m0s
[06:49:40.379] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:122:0)
[06:49:40.379] Timer tick, asking Buildbucket for the build status
[06:49:40.472] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:40.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:123:0) after 1m0s
[06:50:40.544] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:123:0)
[06:50:40.544] Timer tick, asking Buildbucket for the build status
[06:50:40.606] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:40.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:124:0) after 1m0s
[06:51:40.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:124:0)
[06:51:40.657] Timer tick, asking Buildbucket for the build status
[06:51:40.779] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:40.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:125:0) after 1m0s
[06:52:40.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:125:0)
[06:52:40.805] Timer tick, asking Buildbucket for the build status
[06:52:40.869] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:40.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:126:0) after 1m0s
[06:53:40.907] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:126:0)
[06:53:40.907] Timer tick, asking Buildbucket for the build status
[06:53:41.029] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:41.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:127:0) after 1m0s
[06:54:41.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:127:0)
[06:54:41.056] Timer tick, asking Buildbucket for the build status
[06:54:41.190] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:41.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:128:0) after 1m0s
[06:55:41.216] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:128:0)
[06:55:41.216] Timer tick, asking Buildbucket for the build status
[06:55:41.273] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:41.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:129:0) after 1m0s
[06:56:41.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:129:0)
[06:56:41.356] Timer tick, asking Buildbucket for the build status
[06:56:41.513] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:41.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:130:0) after 1m0s
[06:57:41.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:130:0)
[06:57:41.547] Timer tick, asking Buildbucket for the build status
[06:57:41.622] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:41.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:131:0) after 1m0s
[06:58:41.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:131:0)
[06:58:41.655] Timer tick, asking Buildbucket for the build status
[06:58:41.718] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:41.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:132:0) after 1m0s
[06:59:41.769] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:132:0)
[06:59:41.769] Timer tick, asking Buildbucket for the build status
[06:59:41.884] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:41.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:133:0) after 1m0s
[07:00:41.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:133:0)
[07:00:41.937] Timer tick, asking Buildbucket for the build status
[07:00:42.222] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:42.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:134:0) after 1m0s
[07:01:42.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:134:0)
[07:01:42.250] Timer tick, asking Buildbucket for the build status
[07:01:42.537] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:42.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:135:0) after 1m0s
[07:02:42.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:135:0)
[07:02:42.601] Timer tick, asking Buildbucket for the build status
[07:02:42.826] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:42.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:136:0) after 1m0s
[07:03:42.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:136:0)
[07:03:42.912] Timer tick, asking Buildbucket for the build status
[07:03:43.606] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:43.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:137:0) after 1m0s
[07:04:43.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:137:0)
[07:04:43.663] Timer tick, asking Buildbucket for the build status
[07:04:43.830] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:43.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:138:0) after 1m0s
[07:05:43.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:138:0)
[07:05:43.858] Timer tick, asking Buildbucket for the build status
[07:05:43.936] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:43.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:139:0) after 1m0s
[07:06:43.944] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:139:0)
[07:06:43.944] Timer tick, asking Buildbucket for the build status
[07:06:44.410] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:44.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:140:0) after 1m0s
[07:07:44.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:140:0)
[07:07:44.437] Timer tick, asking Buildbucket for the build status
[07:07:44.512] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:44.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:141:0) after 1m0s
[07:08:44.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:141:0)
[07:08:44.539] Timer tick, asking Buildbucket for the build status
[07:08:44.628] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:44.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:142:0) after 1m0s
[07:09:44.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:142:0)
[07:09:44.604] Timer tick, asking Buildbucket for the build status
[07:09:44.847] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:44.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:143:0) after 1m0s
[07:10:44.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:143:0)
[07:10:44.908] Timer tick, asking Buildbucket for the build status
[07:10:44.973] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:44.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:144:0) after 1m0s
[07:11:45.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:144:0)
[07:11:45.042] Timer tick, asking Buildbucket for the build status
[07:11:45.138] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:45.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:145:0) after 1m0s
[07:12:45.162] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:145:0)
[07:12:45.162] Timer tick, asking Buildbucket for the build status
[07:12:45.236] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:45.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:146:0) after 1m0s
[07:13:45.234] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:146:0)
[07:13:45.234] Timer tick, asking Buildbucket for the build status
[07:13:45.332] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:45.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:147:0) after 1m0s
[07:14:45.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:147:0)
[07:14:45.395] Timer tick, asking Buildbucket for the build status
[07:14:45.509] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:45.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:148:0) after 1m0s
[07:15:45.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:148:0)
[07:15:45.627] Timer tick, asking Buildbucket for the build status
[07:15:46.211] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:46.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:149:0) after 1m0s
[07:16:46.290] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:149:0)
[07:16:46.290] Timer tick, asking Buildbucket for the build status
[07:16:46.530] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:46.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:150:0) after 1m0s
[07:17:46.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:150:0)
[07:17:46.603] Timer tick, asking Buildbucket for the build status
[07:17:46.772] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:46.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:151:0) after 1m0s
[07:18:46.782] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:151:0)
[07:18:46.782] Timer tick, asking Buildbucket for the build status
[07:18:46.948] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:46.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:152:0) after 1m0s
[07:19:47.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:152:0)
[07:19:47.135] Timer tick, asking Buildbucket for the build status
[07:19:47.555] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:47.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:153:0) after 1m0s
[07:20:47.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:153:0)
[07:20:47.603] Timer tick, asking Buildbucket for the build status
[07:20:47.834] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:47.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:154:0) after 1m0s
[07:21:47.915] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:154:0)
[07:21:47.915] Timer tick, asking Buildbucket for the build status
[07:21:47.981] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:47.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:155:0) after 1m0s
[07:22:48.054] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:155:0)
[07:22:48.054] Timer tick, asking Buildbucket for the build status
[07:22:48.184] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:48.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:156:0) after 1m0s
[07:23:48.188] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:156:0)
[07:23:48.188] Timer tick, asking Buildbucket for the build status
[07:23:48.258] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:48.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:157:0) after 1m0s
[07:24:48.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:157:0)
[07:24:48.289] Timer tick, asking Buildbucket for the build status
[07:24:48.539] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:48.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:158:0) after 1m0s
[07:25:48.583] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:158:0)
[07:25:48.583] Timer tick, asking Buildbucket for the build status
[07:25:48.652] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:48.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:159:0) after 1m0s
[07:26:48.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:159:0)
[07:26:48.694] Timer tick, asking Buildbucket for the build status
[07:26:48.779] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:48.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:160:0) after 1m0s
[07:27:48.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:160:0)
[07:27:48.888] Timer tick, asking Buildbucket for the build status
[07:27:48.997] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:48.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:161:0) after 1m0s
[07:28:49.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:161:0)
[07:28:49.045] Timer tick, asking Buildbucket for the build status
[07:28:49.193] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:49.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:162:0) after 1m0s
[07:29:49.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:162:0)
[07:29:49.218] Timer tick, asking Buildbucket for the build status
[07:29:49.568] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:49.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:163:0) after 1m0s
[07:30:49.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:163:0)
[07:30:49.662] Timer tick, asking Buildbucket for the build status
[07:30:49.740] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:49.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:164:0) after 1m0s
[07:31:49.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:164:0)
[07:31:49.780] Timer tick, asking Buildbucket for the build status
[07:31:49.849] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:49.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:165:0) after 1m0s
[07:32:49.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:165:0)
[07:32:49.883] Timer tick, asking Buildbucket for the build status
[07:32:49.963] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:49.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:166:0) after 1m0s
[07:33:49.988] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:166:0)
[07:33:49.988] Timer tick, asking Buildbucket for the build status
[07:33:50.062] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:50.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:167:0) after 1m0s
[07:34:50.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:167:0)
[07:34:50.152] Timer tick, asking Buildbucket for the build status
[07:34:50.265] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:50.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:168:0) after 1m0s
[07:35:50.280] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:168:0)
[07:35:50.280] Timer tick, asking Buildbucket for the build status
[07:35:50.382] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:50.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:169:0) after 1m0s
[07:36:50.473] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:169:0)
[07:36:50.473] Timer tick, asking Buildbucket for the build status
[07:36:50.663] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:50.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:170:0) after 1m0s
[07:37:50.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:170:0)
[07:37:50.744] Timer tick, asking Buildbucket for the build status
[07:37:50.871] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:50.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:171:0) after 1m0s
[07:38:50.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:171:0)
[07:38:50.930] Timer tick, asking Buildbucket for the build status
[07:38:50.998] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:50.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:172:0) after 1m0s
[07:39:51.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:172:0)
[07:39:51.118] Timer tick, asking Buildbucket for the build status
[07:39:51.217] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:51.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:173:0) after 1m0s
[07:40:51.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:173:0)
[07:40:51.275] Timer tick, asking Buildbucket for the build status
[07:40:51.342] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:51.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:174:0) after 1m0s
[07:41:51.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:174:0)
[07:41:51.429] Timer tick, asking Buildbucket for the build status
[07:41:51.527] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:51.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:175:0) after 1m0s
[07:42:51.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:175:0)
[07:42:51.708] Timer tick, asking Buildbucket for the build status
[07:42:52.107] Build 8862506139541140720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:52.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9027936201487805168:176:0) after 1m0s
[07:43:50.073] Received PubSub notification, asking Buildbucket for the build status
[07:43:50.138] Build 8862506139541140720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:43:50.138] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606463029267345",
"created_by": "project:chromium",
"created_ts": "1606452537934900",
"id": "8862506139541140720",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a67dba4e985a7119c004d8a142394839f4c5887a\"}}",
"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\": \"a67dba4e985a7119c004d8a142394839f4c5887a\", \"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-7-81na\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1081, \"got_angle_revision\": \"fcb6b5a5c55ee35d232f35bcfa9c35aefc00f89e\", \"got_dawn_revision\": \"4814bdbdeae40186a61dfb7494390e64820daa4f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"a67dba4e985a7119c004d8a142394839f4c5887a\", \"got_revision_cp\": \"refs/heads/master@{#831475}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"961f93287f84e7e7a7f5ff8a43c7adf14ca7fc42\", \"got_v8_revision_cp\": \"refs/heads/8.9.63@{#1}\", \"got_webrtc_revision\": \"0d863f72a8c747c1b41f2798e5201e1abcdaec2b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32704}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a67dba4e985a7119c004d8a142394839f4c5887a\"}, \"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-7-81na\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5640-d0eb544\"], \"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": "1606452747983575",
"status": "COMPLETED",
"status_changed_ts": "1606463029707690",
"tags": [
"build_address:luci.chromium.ci/win-official/1081",
"builder:win-official",
"buildset:commit/git/a67dba4e985a7119c004d8a142394839f4c5887a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a67dba4e985a7119c004d8a142394839f4c5887a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027936201487805168",
"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/8862506139541140720/+/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:5020d9f61bdb3a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606463029730045",
"url": "https://ci.chromium.org/b/8862506139541140720",
"utcnow_ts": "1606463030130296"
}
}
[07:43:50.138] Invocation finished in 2h54m53.444396075s with status SUCCEEDED