[03:20:00.410] New invocation is queued and will start shortly
[03:20:02.534] Starting the invocation (attempt 1)
[03:20:02.576] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:20:02.576] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:20:02.576] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035098957351007792",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"85da8ec53d33a32478a31508c32d1c1b8caf8685\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9035098957351007792",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/85da8ec53d33a32478a31508c32d1c1b8caf8685",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85da8ec53d33a32478a31508c32d1c1b8caf8685",
"gitiles_ref:refs/heads/master"
]
}
[03:20:03.726] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599621602718790",
"id": "8869668894266854416",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"85da8ec53d33a32478a31508c32d1c1b8caf8685\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599621603492673",
"tags": [
"build_address:luci.chromium.ci/win-official/542",
"builder:win-official",
"buildset:commit/git/85da8ec53d33a32478a31508c32d1c1b8caf8685",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85da8ec53d33a32478a31508c32d1c1b8caf8685",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035098957351007792",
"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/8869668894266854416/+/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": "1599621603492853",
"url": "https://ci.chromium.org/b/8869668894266854416",
"utcnow_ts": "1599621603714235"
}
}
[03:20:03.726] Task URL: https://ci.chromium.org/b/8869668894266854416
[03:20:03.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:2:0) after 1m0s
[03:20:06.787] Received PubSub notification, asking Buildbucket for the build status
[03:20:06.876] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:03.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:2:0)
[03:21:03.747] Timer tick, asking Buildbucket for the build status
[03:21:03.925] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:03.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:4:0) after 1m0s
[03:22:03.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:4:0)
[03:22:03.939] Timer tick, asking Buildbucket for the build status
[03:22:04.087] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:04.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:5:0) after 1m0s
[03:23:04.110] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:5:0)
[03:23:04.110] Timer tick, asking Buildbucket for the build status
[03:23:04.260] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:04.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:6:0) after 1m0s
[03:24:04.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:6:0)
[03:24:04.309] Timer tick, asking Buildbucket for the build status
[03:24:04.390] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:04.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:7:0) after 1m0s
[03:25:04.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:7:0)
[03:25:04.431] Timer tick, asking Buildbucket for the build status
[03:25:04.773] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:04.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:8:0) after 1m0s
[03:26:04.793] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:8:0)
[03:26:04.793] Timer tick, asking Buildbucket for the build status
[03:26:05.439] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:05.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:9:0) after 1m0s
[03:27:05.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:9:0)
[03:27:05.542] Timer tick, asking Buildbucket for the build status
[03:27:05.778] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:05.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:10:0) after 1m0s
[03:28:05.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:10:0)
[03:28:05.846] Timer tick, asking Buildbucket for the build status
[03:28:06.992] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:06.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:11:0) after 1m0s
[03:29:07.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:11:0)
[03:29:07.057] Timer tick, asking Buildbucket for the build status
[03:29:07.387] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:07.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:12:0) after 1m0s
[03:30:07.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:12:0)
[03:30:07.404] Timer tick, asking Buildbucket for the build status
[03:30:07.630] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:07.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:13:0) after 1m0s
[03:31:07.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:13:0)
[03:31:07.734] Timer tick, asking Buildbucket for the build status
[03:31:08.189] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:08.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:14:0) after 1m0s
[03:32:08.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:14:0)
[03:32:08.274] Timer tick, asking Buildbucket for the build status
[03:32:08.385] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:08.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:15:0) after 1m0s
[03:33:08.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:15:0)
[03:33:08.419] Timer tick, asking Buildbucket for the build status
[03:33:08.569] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:08.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:16:0) after 1m0s
[03:34:08.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:16:0)
[03:34:08.613] Timer tick, asking Buildbucket for the build status
[03:34:08.721] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:08.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:17:0) after 1m0s
[03:35:08.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:17:0)
[03:35:08.848] Timer tick, asking Buildbucket for the build status
[03:35:09.248] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:09.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:18:0) after 1m0s
[03:36:09.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:18:0)
[03:36:09.317] Timer tick, asking Buildbucket for the build status
[03:36:09.716] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:09.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:19:0) after 1m0s
[03:37:09.722] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:19:0)
[03:37:09.722] Timer tick, asking Buildbucket for the build status
[03:37:11.052] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:11.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:20:0) after 1m0s
[03:38:11.103] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:20:0)
[03:38:11.103] Timer tick, asking Buildbucket for the build status
[03:38:11.185] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:11.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:21:0) after 1m0s
[03:39:11.255] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:21:0)
[03:39:11.255] Timer tick, asking Buildbucket for the build status
[03:39:11.386] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:11.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:22:0) after 1m0s
[03:40:11.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:22:0)
[03:40:11.417] Timer tick, asking Buildbucket for the build status
[03:40:13.385] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:13.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:23:0) after 1m0s
[03:41:13.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:23:0)
[03:41:13.398] Timer tick, asking Buildbucket for the build status
[03:41:14.293] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:14.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:24:0) after 1m0s
[03:42:14.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:24:0)
[03:42:14.383] Timer tick, asking Buildbucket for the build status
[03:42:14.594] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:14.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:25:0) after 1m0s
[03:43:14.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:25:0)
[03:43:14.612] Timer tick, asking Buildbucket for the build status
[03:43:14.751] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:14.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:26:0) after 1m0s
[03:44:14.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:26:0)
[03:44:14.822] Timer tick, asking Buildbucket for the build status
[03:44:15.045] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:15.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:27:0) after 1m0s
[03:45:15.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:27:0)
[03:45:15.072] Timer tick, asking Buildbucket for the build status
[03:45:15.839] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:15.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:28:0) after 1m0s
[03:46:15.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:28:0)
[03:46:15.898] Timer tick, asking Buildbucket for the build status
[03:46:15.974] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:15.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:29:0) after 1m0s
[03:47:15.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:29:0)
[03:47:15.960] Timer tick, asking Buildbucket for the build status
[03:47:16.082] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:16.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:30:0) after 1m0s
[03:48:16.114] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:30:0)
[03:48:16.114] Timer tick, asking Buildbucket for the build status
[03:48:16.368] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:16.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:31:0) after 1m0s
[03:49:16.463] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:31:0)
[03:49:16.463] Timer tick, asking Buildbucket for the build status
[03:49:16.566] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:16.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:32:0) after 1m0s
[03:50:16.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:32:0)
[03:50:16.605] Timer tick, asking Buildbucket for the build status
[03:50:17.663] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:17.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:33:0) after 1m0s
[03:51:17.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:33:0)
[03:51:17.685] Timer tick, asking Buildbucket for the build status
[03:51:17.783] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:17.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:34:0) after 1m0s
[03:52:17.796] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:34:0)
[03:52:17.796] Timer tick, asking Buildbucket for the build status
[03:52:17.864] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:17.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:35:0) after 1m0s
[03:53:17.878] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:35:0)
[03:53:17.878] Timer tick, asking Buildbucket for the build status
[03:53:18.114] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:18.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:36:0) after 1m0s
[03:54:18.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:36:0)
[03:54:18.192] Timer tick, asking Buildbucket for the build status
[03:54:18.344] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:18.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:37:0) after 1m0s
[03:55:18.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:37:0)
[03:55:18.601] Timer tick, asking Buildbucket for the build status
[03:55:18.781] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:18.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:38:0) after 1m0s
[03:56:19.014] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:38:0)
[03:56:19.014] Timer tick, asking Buildbucket for the build status
[03:56:19.137] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:19.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:39:0) after 1m0s
[03:57:19.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:39:0)
[03:57:19.378] Timer tick, asking Buildbucket for the build status
[03:57:19.546] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:19.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:40:0) after 1m0s
[03:58:19.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:40:0)
[03:58:19.600] Timer tick, asking Buildbucket for the build status
[03:58:19.684] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:19.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:41:0) after 1m0s
[03:59:19.751] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:41:0)
[03:59:19.751] Timer tick, asking Buildbucket for the build status
[03:59:19.994] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:19.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:42:0) after 1m0s
[04:00:20.031] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:42:0)
[04:00:20.031] Timer tick, asking Buildbucket for the build status
[04:00:20.544] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:20.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:43:0) after 1m0s
[04:01:20.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:43:0)
[04:01:20.574] Timer tick, asking Buildbucket for the build status
[04:01:22.167] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:22.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:44:0) after 1m0s
[04:02:22.188] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:44:0)
[04:02:22.188] Timer tick, asking Buildbucket for the build status
[04:02:22.298] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:22.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:45:0) after 1m0s
[04:03:22.376] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:45:0)
[04:03:22.376] Timer tick, asking Buildbucket for the build status
[04:03:22.576] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:22.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:46:0) after 1m0s
[04:04:22.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:46:0)
[04:04:22.633] Timer tick, asking Buildbucket for the build status
[04:04:22.857] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:22.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:47:0) after 1m0s
[04:05:22.893] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:47:0)
[04:05:22.893] Timer tick, asking Buildbucket for the build status
[04:05:23.149] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:23.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:48:0) after 1m0s
[04:06:23.184] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:48:0)
[04:06:23.184] Timer tick, asking Buildbucket for the build status
[04:06:23.291] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:23.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:49:0) after 1m0s
[04:07:23.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:49:0)
[04:07:23.310] Timer tick, asking Buildbucket for the build status
[04:07:24.202] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:24.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:50:0) after 1m0s
[04:08:24.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:50:0)
[04:08:24.238] Timer tick, asking Buildbucket for the build status
[04:08:24.471] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:24.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:51:0) after 1m0s
[04:09:24.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:51:0)
[04:09:24.552] Timer tick, asking Buildbucket for the build status
[04:09:24.665] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:24.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:52:0) after 1m0s
[04:10:24.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:52:0)
[04:10:24.672] Timer tick, asking Buildbucket for the build status
[04:10:24.870] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:24.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:53:0) after 1m0s
[04:11:24.934] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:53:0)
[04:11:24.934] Timer tick, asking Buildbucket for the build status
[04:11:25.110] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:25.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:54:0) after 1m0s
[04:12:25.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:54:0)
[04:12:25.151] Timer tick, asking Buildbucket for the build status
[04:12:25.671] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:25.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:55:0) after 1m0s
[04:13:25.708] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:55:0)
[04:13:25.708] Timer tick, asking Buildbucket for the build status
[04:13:26.117] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:26.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:56:0) after 1m0s
[04:14:26.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:56:0)
[04:14:26.193] Timer tick, asking Buildbucket for the build status
[04:14:26.323] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:26.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:57:0) after 1m0s
[04:15:26.463] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:57:0)
[04:15:26.463] Timer tick, asking Buildbucket for the build status
[04:15:26.900] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:26.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:58:0) after 1m0s
[04:16:26.943] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:58:0)
[04:16:26.943] Timer tick, asking Buildbucket for the build status
[04:16:27.435] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:27.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:59:0) after 1m0s
[04:17:27.504] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:59:0)
[04:17:27.504] Timer tick, asking Buildbucket for the build status
[04:17:27.564] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:27.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:60:0) after 1m0s
[04:18:27.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:60:0)
[04:18:27.664] Timer tick, asking Buildbucket for the build status
[04:18:28.696] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:28.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:61:0) after 1m0s
[04:19:28.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:61:0)
[04:19:28.736] Timer tick, asking Buildbucket for the build status
[04:19:29.466] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:29.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:62:0) after 1m0s
[04:20:29.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:62:0)
[04:20:29.488] Timer tick, asking Buildbucket for the build status
[04:20:29.665] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:29.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:63:0) after 1m0s
[04:21:29.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:63:0)
[04:21:29.660] Timer tick, asking Buildbucket for the build status
[04:21:29.784] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:29.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:64:0) after 1m0s
[04:22:29.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:64:0)
[04:22:29.876] Timer tick, asking Buildbucket for the build status
[04:22:30.174] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:30.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:65:0) after 1m0s
[04:23:30.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:65:0)
[04:23:30.685] Timer tick, asking Buildbucket for the build status
[04:23:31.133] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:31.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:66:0) after 1m0s
[04:24:31.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:66:0)
[04:24:31.279] Timer tick, asking Buildbucket for the build status
[04:24:32.776] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:32.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:67:0) after 1m0s
[04:25:32.857] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:67:0)
[04:25:32.857] Timer tick, asking Buildbucket for the build status
[04:25:33.070] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:33.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:68:0) after 1m0s
[04:26:33.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:68:0)
[04:26:33.094] Timer tick, asking Buildbucket for the build status
[04:26:33.296] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:33.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:69:0) after 1m0s
[04:27:33.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:69:0)
[04:27:33.304] Timer tick, asking Buildbucket for the build status
[04:27:33.502] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:33.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:70:0) after 1m0s
[04:28:33.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:70:0)
[04:28:33.637] Timer tick, asking Buildbucket for the build status
[04:28:33.760] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:33.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:71:0) after 1m0s
[04:29:33.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:71:0)
[04:29:33.876] Timer tick, asking Buildbucket for the build status
[04:29:34.275] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:34.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:72:0) after 1m0s
[04:30:34.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:72:0)
[04:30:34.340] Timer tick, asking Buildbucket for the build status
[04:30:35.608] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:35.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:73:0) after 1m0s
[04:31:35.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:73:0)
[04:31:35.639] Timer tick, asking Buildbucket for the build status
[04:31:35.736] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:35.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:74:0) after 1m0s
[04:32:36.028] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:74:0)
[04:32:36.028] Timer tick, asking Buildbucket for the build status
[04:32:36.201] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:36.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:75:0) after 1m0s
[04:33:36.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:75:0)
[04:33:36.352] Timer tick, asking Buildbucket for the build status
[04:33:36.480] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:36.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:76:0) after 1m0s
[04:34:36.508] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:76:0)
[04:34:36.508] Timer tick, asking Buildbucket for the build status
[04:34:37.310] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:37.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:77:0) after 1m0s
[04:35:37.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:77:0)
[04:35:37.335] Timer tick, asking Buildbucket for the build status
[04:35:37.594] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:37.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:78:0) after 1m0s
[04:36:37.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:78:0)
[04:36:37.612] Timer tick, asking Buildbucket for the build status
[04:36:37.682] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:37.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:79:0) after 1m0s
[04:37:37.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:79:0)
[04:37:37.687] Timer tick, asking Buildbucket for the build status
[04:37:37.803] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:37.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:80:0) after 1m0s
[04:38:37.928] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:80:0)
[04:38:37.928] Timer tick, asking Buildbucket for the build status
[04:38:38.006] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:38.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:81:0) after 1m0s
[04:39:38.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:81:0)
[04:39:38.077] Timer tick, asking Buildbucket for the build status
[04:39:38.164] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:38.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:82:0) after 1m0s
[04:40:38.376] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:82:0)
[04:40:38.376] Timer tick, asking Buildbucket for the build status
[04:40:38.800] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:38.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:83:0) after 1m0s
[04:41:38.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:83:0)
[04:41:38.870] Timer tick, asking Buildbucket for the build status
[04:41:39.112] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:39.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:84:0) after 1m0s
[04:42:39.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:84:0)
[04:42:39.111] Timer tick, asking Buildbucket for the build status
[04:42:39.413] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:39.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:85:0) after 1m0s
[04:43:39.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:85:0)
[04:43:39.485] Timer tick, asking Buildbucket for the build status
[04:43:39.651] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:39.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:86:0) after 1m0s
[04:44:39.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:86:0)
[04:44:39.822] Timer tick, asking Buildbucket for the build status
[04:44:40.094] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:40.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:87:0) after 1m0s
[04:45:40.150] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:87:0)
[04:45:40.150] Timer tick, asking Buildbucket for the build status
[04:45:40.310] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:40.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:88:0) after 1m0s
[04:46:40.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:88:0)
[04:46:40.387] Timer tick, asking Buildbucket for the build status
[04:46:40.470] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:40.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:89:0) after 1m0s
[04:47:40.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:89:0)
[04:47:40.502] Timer tick, asking Buildbucket for the build status
[04:47:40.589] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:40.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:90:0) after 1m0s
[04:48:40.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:90:0)
[04:48:40.886] Timer tick, asking Buildbucket for the build status
[04:48:41.059] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:41.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:91:0) after 1m0s
[04:49:41.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:91:0)
[04:49:41.535] Timer tick, asking Buildbucket for the build status
[04:49:41.657] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:41.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:92:0) after 1m0s
[04:50:41.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:92:0)
[04:50:41.877] Timer tick, asking Buildbucket for the build status
[04:50:42.073] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:42.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:93:0) after 1m0s
[04:51:42.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:93:0)
[04:51:42.167] Timer tick, asking Buildbucket for the build status
[04:51:42.258] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:42.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:94:0) after 1m0s
[04:52:42.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:94:0)
[04:52:42.337] Timer tick, asking Buildbucket for the build status
[04:52:43.969] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:43.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:95:0) after 1m0s
[04:53:44.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:95:0)
[04:53:44.086] Timer tick, asking Buildbucket for the build status
[04:53:44.231] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:44.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:96:0) after 1m0s
[04:54:44.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:96:0)
[04:54:44.293] Timer tick, asking Buildbucket for the build status
[04:54:44.395] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:44.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:97:0) after 1m0s
[04:55:44.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:97:0)
[04:55:44.410] Timer tick, asking Buildbucket for the build status
[04:55:44.616] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:44.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:98:0) after 1m0s
[04:56:44.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:98:0)
[04:56:44.646] Timer tick, asking Buildbucket for the build status
[04:56:44.839] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:44.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:99:0) after 1m0s
[04:57:44.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:99:0)
[04:57:44.976] Timer tick, asking Buildbucket for the build status
[04:57:45.620] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:45.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:100:0) after 1m0s
[04:58:45.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:100:0)
[04:58:45.926] Timer tick, asking Buildbucket for the build status
[04:58:46.025] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:46.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:101:0) after 1m0s
[04:59:46.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:101:0)
[04:59:46.036] Timer tick, asking Buildbucket for the build status
[04:59:46.190] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:46.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:102:0) after 1m0s
[05:00:46.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:102:0)
[05:00:46.229] Timer tick, asking Buildbucket for the build status
[05:00:46.454] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:46.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:103:0) after 1m0s
[05:01:46.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:103:0)
[05:01:46.542] Timer tick, asking Buildbucket for the build status
[05:01:46.650] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:46.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:104:0) after 1m0s
[05:02:46.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:104:0)
[05:02:46.774] Timer tick, asking Buildbucket for the build status
[05:02:47.057] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:47.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:105:0) after 1m0s
[05:03:47.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:105:0)
[05:03:47.186] Timer tick, asking Buildbucket for the build status
[05:03:47.299] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:47.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:106:0) after 1m0s
[05:04:47.379] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:106:0)
[05:04:47.379] Timer tick, asking Buildbucket for the build status
[05:04:47.520] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:47.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:107:0) after 1m0s
[05:05:48.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:107:0)
[05:05:48.261] Timer tick, asking Buildbucket for the build status
[05:05:48.502] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:48.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:108:0) after 1m0s
[05:06:48.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:108:0)
[05:06:48.687] Timer tick, asking Buildbucket for the build status
[05:06:48.821] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:48.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:109:0) after 1m0s
[05:07:48.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:109:0)
[05:07:48.855] Timer tick, asking Buildbucket for the build status
[05:07:48.968] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:48.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:110:0) after 1m0s
[05:08:49.186] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:110:0)
[05:08:49.187] Timer tick, asking Buildbucket for the build status
[05:08:49.418] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:49.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:111:0) after 1m0s
[05:09:49.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:111:0)
[05:09:49.452] Timer tick, asking Buildbucket for the build status
[05:09:49.846] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:49.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:112:0) after 1m0s
[05:10:49.884] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:112:0)
[05:10:49.884] Timer tick, asking Buildbucket for the build status
[05:10:50.264] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:50.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:113:0) after 1m0s
[05:11:50.399] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:113:0)
[05:11:50.399] Timer tick, asking Buildbucket for the build status
[05:11:50.512] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:50.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:114:0) after 1m0s
[05:12:50.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:114:0)
[05:12:50.756] Timer tick, asking Buildbucket for the build status
[05:12:50.874] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:50.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:115:0) after 1m0s
[05:13:51.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:115:0)
[05:13:51.192] Timer tick, asking Buildbucket for the build status
[05:13:51.277] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:51.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:116:0) after 1m0s
[05:14:51.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:116:0)
[05:14:51.266] Timer tick, asking Buildbucket for the build status
[05:14:51.397] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:51.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:117:0) after 1m0s
[05:15:51.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:117:0)
[05:15:51.553] Timer tick, asking Buildbucket for the build status
[05:15:52.787] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:52.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:118:0) after 1m0s
[05:16:52.831] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:118:0)
[05:16:52.831] Timer tick, asking Buildbucket for the build status
[05:16:52.923] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:52.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:119:0) after 1m0s
[05:17:52.984] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:119:0)
[05:17:52.984] Timer tick, asking Buildbucket for the build status
[05:17:53.186] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:53.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:120:0) after 1m0s
[05:18:53.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:120:0)
[05:18:53.174] Timer tick, asking Buildbucket for the build status
[05:18:53.439] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:53.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:121:0) after 1m0s
[05:19:53.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:121:0)
[05:19:53.580] Timer tick, asking Buildbucket for the build status
[05:19:53.884] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:53.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:122:0) after 1m0s
[05:20:53.910] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:122:0)
[05:20:53.910] Timer tick, asking Buildbucket for the build status
[05:20:55.514] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:55.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:123:0) after 1m0s
[05:21:55.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:123:0)
[05:21:55.640] Timer tick, asking Buildbucket for the build status
[05:21:55.796] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:55.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:124:0) after 1m0s
[05:22:55.950] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:124:0)
[05:22:55.950] Timer tick, asking Buildbucket for the build status
[05:22:56.014] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:56.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:125:0) after 1m0s
[05:23:56.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:125:0)
[05:23:56.109] Timer tick, asking Buildbucket for the build status
[05:23:56.299] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:56.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:126:0) after 1m0s
[05:24:56.315] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:126:0)
[05:24:56.315] Timer tick, asking Buildbucket for the build status
[05:24:56.469] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:56.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:127:0) after 1m0s
[05:25:56.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:127:0)
[05:25:56.498] Timer tick, asking Buildbucket for the build status
[05:25:56.679] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:56.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:128:0) after 1m0s
[05:26:56.738] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:128:0)
[05:26:56.738] Timer tick, asking Buildbucket for the build status
[05:26:56.847] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:56.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:129:0) after 1m0s
[05:27:56.977] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:129:0)
[05:27:56.977] Timer tick, asking Buildbucket for the build status
[05:27:57.284] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:57.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:130:0) after 1m0s
[05:28:57.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:130:0)
[05:28:57.361] Timer tick, asking Buildbucket for the build status
[05:28:58.180] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:58.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:131:0) after 1m0s
[05:29:58.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:131:0)
[05:29:58.214] Timer tick, asking Buildbucket for the build status
[05:29:58.279] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:58.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:132:0) after 1m0s
[05:30:58.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:132:0)
[05:30:58.371] Timer tick, asking Buildbucket for the build status
[05:30:58.630] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:58.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:133:0) after 1m0s
[05:31:58.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:133:0)
[05:31:58.741] Timer tick, asking Buildbucket for the build status
[05:32:00.742] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:00.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:134:0) after 1m0s
[05:33:00.897] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:134:0)
[05:33:00.897] Timer tick, asking Buildbucket for the build status
[05:33:01.157] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:01.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:135:0) after 1m0s
[05:34:01.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:135:0)
[05:34:01.551] Timer tick, asking Buildbucket for the build status
[05:34:01.668] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:01.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:136:0) after 1m0s
[05:35:02.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:136:0)
[05:35:02.849] Timer tick, asking Buildbucket for the build status
[05:35:03.093] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:03.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:137:0) after 1m0s
[05:36:03.149] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:137:0)
[05:36:03.149] Timer tick, asking Buildbucket for the build status
[05:36:04.146] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:04.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:138:0) after 1m0s
[05:37:04.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:138:0)
[05:37:04.172] Timer tick, asking Buildbucket for the build status
[05:37:04.557] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:04.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:139:0) after 1m0s
[05:38:04.624] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:139:0)
[05:38:04.624] Timer tick, asking Buildbucket for the build status
[05:38:04.772] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:04.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:140:0) after 1m0s
[05:39:04.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:140:0)
[05:39:04.805] Timer tick, asking Buildbucket for the build status
[05:39:05.156] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:05.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:141:0) after 1m0s
[05:40:05.180] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:141:0)
[05:40:05.180] Timer tick, asking Buildbucket for the build status
[05:40:05.275] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:05.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:142:0) after 1m0s
[05:41:05.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:142:0)
[05:41:05.358] Timer tick, asking Buildbucket for the build status
[05:41:05.517] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:05.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:143:0) after 1m0s
[05:42:05.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:143:0)
[05:42:05.667] Timer tick, asking Buildbucket for the build status
[05:42:05.957] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:05.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:144:0) after 1m0s
[05:43:05.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:144:0)
[05:43:05.991] Timer tick, asking Buildbucket for the build status
[05:43:07.879] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:07.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:145:0) after 1m0s
[05:44:07.943] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:145:0)
[05:44:07.943] Timer tick, asking Buildbucket for the build status
[05:44:08.130] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:08.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:146:0) after 1m0s
[05:45:08.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:146:0)
[05:45:08.160] Timer tick, asking Buildbucket for the build status
[05:45:08.303] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:08.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:147:0) after 1m0s
[05:46:08.305] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:147:0)
[05:46:08.305] Timer tick, asking Buildbucket for the build status
[05:46:08.623] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:08.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:148:0) after 1m0s
[05:47:08.752] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:148:0)
[05:47:08.752] Timer tick, asking Buildbucket for the build status
[05:47:08.983] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:08.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:149:0) after 1m0s
[05:48:09.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:149:0)
[05:48:09.101] Timer tick, asking Buildbucket for the build status
[05:48:09.251] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:09.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:150:0) after 1m0s
[05:49:09.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:150:0)
[05:49:09.731] Timer tick, asking Buildbucket for the build status
[05:49:09.844] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:09.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:151:0) after 1m0s
[05:50:09.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:151:0)
[05:50:09.911] Timer tick, asking Buildbucket for the build status
[05:50:12.000] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:12.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:152:0) after 1m0s
[05:51:12.094] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:152:0)
[05:51:12.094] Timer tick, asking Buildbucket for the build status
[05:51:12.645] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:12.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:153:0) after 1m0s
[05:52:12.885] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:153:0)
[05:52:12.886] Timer tick, asking Buildbucket for the build status
[05:52:13.363] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:13.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:154:0) after 1m0s
[05:53:13.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:154:0)
[05:53:13.422] Timer tick, asking Buildbucket for the build status
[05:53:13.538] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:13.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:155:0) after 1m0s
[05:54:13.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:155:0)
[05:54:13.652] Timer tick, asking Buildbucket for the build status
[05:54:13.917] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:13.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:156:0) after 1m0s
[05:55:14.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:156:0)
[05:55:14.012] Timer tick, asking Buildbucket for the build status
[05:55:14.105] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:14.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:157:0) after 1m0s
[05:56:14.202] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:157:0)
[05:56:14.202] Timer tick, asking Buildbucket for the build status
[05:56:15.358] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:15.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:158:0) after 1m0s
[05:57:15.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:158:0)
[05:57:15.394] Timer tick, asking Buildbucket for the build status
[05:57:15.618] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:15.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:159:0) after 1m0s
[05:58:16.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:159:0)
[05:58:16.029] Timer tick, asking Buildbucket for the build status
[05:58:16.244] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:16.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:160:0) after 1m0s
[05:59:16.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:160:0)
[05:59:16.870] Timer tick, asking Buildbucket for the build status
[05:59:17.077] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:17.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:161:0) after 1m0s
[06:00:25.512] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:161:0)
[06:00:25.512] Timer tick, asking Buildbucket for the build status
[06:00:27.763] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:27.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:162:0) after 1m0s
[06:01:27.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:162:0)
[06:01:27.877] Timer tick, asking Buildbucket for the build status
[06:01:27.963] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:27.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:163:0) after 1m0s
[06:02:28.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:163:0)
[06:02:28.057] Timer tick, asking Buildbucket for the build status
[06:02:28.183] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:28.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:164:0) after 1m0s
[06:03:28.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:164:0)
[06:03:28.482] Timer tick, asking Buildbucket for the build status
[06:03:28.618] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:28.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:165:0) after 1m0s
[06:04:28.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:165:0)
[06:04:28.633] Timer tick, asking Buildbucket for the build status
[06:04:29.727] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:29.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:166:0) after 1m0s
[06:05:29.831] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:166:0)
[06:05:29.831] Timer tick, asking Buildbucket for the build status
[06:05:30.132] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:30.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:167:0) after 1m0s
[06:06:30.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:167:0)
[06:06:30.189] Timer tick, asking Buildbucket for the build status
[06:06:30.463] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:30.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:168:0) after 1m0s
[06:07:30.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:168:0)
[06:07:30.499] Timer tick, asking Buildbucket for the build status
[06:07:30.756] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:30.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:169:0) after 1m0s
[06:08:30.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:169:0)
[06:08:30.787] Timer tick, asking Buildbucket for the build status
[06:08:30.875] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:30.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:170:0) after 1m0s
[06:09:30.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:170:0)
[06:09:30.989] Timer tick, asking Buildbucket for the build status
[06:09:31.241] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:31.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:171:0) after 1m0s
[06:10:31.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:171:0)
[06:10:31.272] Timer tick, asking Buildbucket for the build status
[06:10:31.391] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:31.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:172:0) after 1m0s
[06:11:31.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:172:0)
[06:11:31.429] Timer tick, asking Buildbucket for the build status
[06:11:31.717] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:31.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:173:0) after 1m0s
[06:12:31.824] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:173:0)
[06:12:31.824] Timer tick, asking Buildbucket for the build status
[06:12:31.931] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:31.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:174:0) after 1m0s
[06:13:31.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:174:0)
[06:13:31.962] Timer tick, asking Buildbucket for the build status
[06:13:32.065] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:32.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:175:0) after 1m0s
[06:14:32.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:175:0)
[06:14:32.096] Timer tick, asking Buildbucket for the build status
[06:14:32.203] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:32.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:176:0) after 1m0s
[06:15:32.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:176:0)
[06:15:32.309] Timer tick, asking Buildbucket for the build status
[06:15:32.675] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:32.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:177:0) after 1m0s
[06:16:32.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:177:0)
[06:16:32.969] Timer tick, asking Buildbucket for the build status
[06:16:34.451] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:34.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:178:0) after 1m0s
[06:17:34.557] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:178:0)
[06:17:34.557] Timer tick, asking Buildbucket for the build status
[06:17:34.824] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:34.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:179:0) after 1m0s
[06:18:34.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:179:0)
[06:18:34.848] Timer tick, asking Buildbucket for the build status
[06:18:35.040] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:35.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:180:0) after 1m0s
[06:19:35.098] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:180:0)
[06:19:35.098] Timer tick, asking Buildbucket for the build status
[06:19:35.386] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:35.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:181:0) after 1m0s
[06:20:35.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:181:0)
[06:20:35.451] Timer tick, asking Buildbucket for the build status
[06:20:35.608] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:35.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:182:0) after 1m0s
[06:21:35.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:182:0)
[06:21:35.694] Timer tick, asking Buildbucket for the build status
[06:21:35.987] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:35.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:183:0) after 1m0s
[06:22:36.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:183:0)
[06:22:36.003] Timer tick, asking Buildbucket for the build status
[06:22:37.634] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:37.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:184:0) after 1m0s
[06:23:37.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:184:0)
[06:23:37.676] Timer tick, asking Buildbucket for the build status
[06:23:37.776] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:37.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:185:0) after 1m0s
[06:24:37.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:185:0)
[06:24:37.861] Timer tick, asking Buildbucket for the build status
[06:24:38.076] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:38.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:186:0) after 1m0s
[06:25:38.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:186:0)
[06:25:38.204] Timer tick, asking Buildbucket for the build status
[06:25:38.431] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:38.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:187:0) after 1m0s
[06:26:38.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:187:0)
[06:26:38.484] Timer tick, asking Buildbucket for the build status
[06:26:38.683] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:38.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:188:0) after 1m0s
[06:27:38.706] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:188:0)
[06:27:38.706] Timer tick, asking Buildbucket for the build status
[06:27:38.863] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:38.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:189:0) after 1m0s
[06:28:38.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:189:0)
[06:28:38.978] Timer tick, asking Buildbucket for the build status
[06:28:39.131] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:39.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:190:0) after 1m0s
[06:29:39.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:190:0)
[06:29:39.165] Timer tick, asking Buildbucket for the build status
[06:29:39.385] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:39.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:191:0) after 1m0s
[06:30:39.473] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:191:0)
[06:30:39.473] Timer tick, asking Buildbucket for the build status
[06:30:39.677] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:39.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:192:0) after 1m0s
[06:31:39.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:192:0)
[06:31:39.873] Timer tick, asking Buildbucket for the build status
[06:31:40.033] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:40.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:193:0) after 1m0s
[06:32:40.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:193:0)
[06:32:40.144] Timer tick, asking Buildbucket for the build status
[06:32:40.249] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:40.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:194:0) after 1m0s
[06:33:40.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:194:0)
[06:33:40.407] Timer tick, asking Buildbucket for the build status
[06:33:41.467] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:41.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:195:0) after 1m0s
[06:34:41.716] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:195:0)
[06:34:41.716] Timer tick, asking Buildbucket for the build status
[06:34:41.849] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:41.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:196:0) after 1m0s
[06:35:42.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:196:0)
[06:35:42.204] Timer tick, asking Buildbucket for the build status
[06:35:42.494] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:42.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:197:0) after 1m0s
[06:36:42.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:197:0)
[06:36:42.520] Timer tick, asking Buildbucket for the build status
[06:36:42.596] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:42.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:198:0) after 1m0s
[06:37:42.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:198:0)
[06:37:42.758] Timer tick, asking Buildbucket for the build status
[06:37:43.051] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:43.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:199:0) after 1m0s
[06:38:43.138] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:199:0)
[06:38:43.138] Timer tick, asking Buildbucket for the build status
[06:38:43.528] Build 8869668894266854416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:43.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035098957351007792:200:0) after 1m0s
[06:39:03.969] Received PubSub notification, asking Buildbucket for the build status
[06:39:04.275] Build 8869668894266854416: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:39:04.275] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599633543014062",
"created_by": "project:chromium",
"created_ts": "1599621602718790",
"id": "8869668894266854416",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"85da8ec53d33a32478a31508c32d1c1b8caf8685\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"85da8ec53d33a32478a31508c32d1c1b8caf8685\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-8-0oxp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 542, \"got_angle_revision\": \"41c93c550b0720c3945a3c6efee6ee72e608b95c\", \"got_dawn_revision\": \"22e5e179fa288848b059b563f1d2b7c32e804eff\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"85da8ec53d33a32478a31508c32d1c1b8caf8685\", \"got_revision_cp\": \"refs/heads/master@{#805192}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"10121443c16deb0435be8ea61be54e3bee1b9855\", \"got_v8_revision\": \"bb4817544d7ce327fc432a8229efa213952ca979\", \"got_v8_revision_cp\": \"refs/heads/8.7.76@{#1}\", \"got_webrtc_revision\": \"9e9c8b7155d49c2f38b82fe9c9707181c57073c9\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32051}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"85da8ec53d33a32478a31508c32d1c1b8caf8685\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-0oxp\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"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\": [\"5513-ee65082\"], \"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": "1599621605963564",
"status": "COMPLETED",
"status_changed_ts": "1599633543574929",
"tags": [
"build_address:luci.chromium.ci/win-official/542",
"builder:win-official",
"buildset:commit/git/85da8ec53d33a32478a31508c32d1c1b8caf8685",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85da8ec53d33a32478a31508c32d1c1b8caf8685",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035098957351007792",
"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/8869668894266854416/+/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:4e89b22a7965f010",
"user_agent:luci-scheduler"
],
"updated_ts": "1599633543582545",
"url": "https://ci.chromium.org/b/8869668894266854416",
"utcnow_ts": "1599633544197458"
}
}
[06:39:04.275] Invocation finished in 3h19m3.881946775s with status SUCCEEDED