[02:07:10.646] New invocation is queued and will start shortly
[02:07:11.841] Starting the invocation (attempt 1)
[02:07:11.892] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:07:11.893] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:07:11.895] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035737718148153856",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ec2e2f992dfa2f17fea5083267850ea02313046d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9035737718148153856",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/ec2e2f992dfa2f17fea5083267850ea02313046d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ec2e2f992dfa2f17fea5083267850ea02313046d",
"gitiles_ref:refs/heads/master"
]
}
[02:07:12.701] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599012432024016",
"id": "8870307656035809536",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ec2e2f992dfa2f17fea5083267850ea02313046d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599012432463660",
"tags": [
"build_address:luci.chromium.ci/win-official/484",
"builder:win-official",
"buildset:commit/git/ec2e2f992dfa2f17fea5083267850ea02313046d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ec2e2f992dfa2f17fea5083267850ea02313046d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035737718148153856",
"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/8870307656035809536/+/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": "1599012432463815",
"url": "https://ci.chromium.org/b/8870307656035809536",
"utcnow_ts": "1599012432684411"
}
}
[02:07:12.701] Task URL: https://ci.chromium.org/b/8870307656035809536
[02:07:12.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:2:0) after 1m0s
[02:07:51.302] Received PubSub notification, asking Buildbucket for the build status
[02:07:51.426] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:12.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:2:0)
[02:08:12.818] Timer tick, asking Buildbucket for the build status
[02:08:13.090] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:13.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:4:0) after 1m0s
[02:09:13.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:4:0)
[02:09:13.149] Timer tick, asking Buildbucket for the build status
[02:09:13.381] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:13.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:5:0) after 1m0s
[02:10:13.457] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:5:0)
[02:10:13.457] Timer tick, asking Buildbucket for the build status
[02:10:13.771] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:13.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:6:0) after 1m0s
[02:11:14.071] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:6:0)
[02:11:14.071] Timer tick, asking Buildbucket for the build status
[02:11:14.433] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:14.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:7:0) after 1m0s
[02:12:15.399] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:7:0)
[02:12:15.399] Timer tick, asking Buildbucket for the build status
[02:12:16.210] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:16.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:8:0) after 1m0s
[02:13:16.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:8:0)
[02:13:16.405] Timer tick, asking Buildbucket for the build status
[02:13:16.513] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:16.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:9:0) after 1m0s
[02:14:16.771] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:9:0)
[02:14:16.771] Timer tick, asking Buildbucket for the build status
[02:14:16.889] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:16.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:10:0) after 1m0s
[02:15:17.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:10:0)
[02:15:17.218] Timer tick, asking Buildbucket for the build status
[02:15:17.326] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:17.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:11:0) after 1m0s
[02:16:17.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:11:0)
[02:16:17.555] Timer tick, asking Buildbucket for the build status
[02:16:17.672] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:17.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:12:0) after 1m0s
[02:17:17.857] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:12:0)
[02:17:17.857] Timer tick, asking Buildbucket for the build status
[02:17:18.048] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:18.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:13:0) after 1m0s
[02:18:18.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:13:0)
[02:18:18.933] Timer tick, asking Buildbucket for the build status
[02:18:19.175] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:19.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:14:0) after 1m0s
[02:19:19.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:14:0)
[02:19:19.292] Timer tick, asking Buildbucket for the build status
[02:19:19.501] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:19.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:15:0) after 1m0s
[02:20:19.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:15:0)
[02:20:19.535] Timer tick, asking Buildbucket for the build status
[02:20:19.626] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:19.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:16:0) after 1m0s
[02:21:19.766] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:16:0)
[02:21:19.766] Timer tick, asking Buildbucket for the build status
[02:21:20.053] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:20.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:17:0) after 1m0s
[02:22:20.216] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:17:0)
[02:22:20.216] Timer tick, asking Buildbucket for the build status
[02:22:20.337] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:20.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:18:0) after 1m0s
[02:23:20.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:18:0)
[02:23:20.419] Timer tick, asking Buildbucket for the build status
[02:23:20.512] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:20.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:19:0) after 1m0s
[02:24:20.917] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:19:0)
[02:24:20.917] Timer tick, asking Buildbucket for the build status
[02:24:21.119] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:21.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:20:0) after 1m0s
[02:25:21.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:20:0)
[02:25:21.258] Timer tick, asking Buildbucket for the build status
[02:25:24.001] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:24.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:21:0) after 1m0s
[02:26:24.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:21:0)
[02:26:24.017] Timer tick, asking Buildbucket for the build status
[02:26:24.189] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:24.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:22:0) after 1m0s
[02:27:24.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:22:0)
[02:27:24.342] Timer tick, asking Buildbucket for the build status
[02:27:24.526] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:24.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:23:0) after 1m0s
[02:28:24.638] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:23:0)
[02:28:24.638] Timer tick, asking Buildbucket for the build status
[02:28:24.835] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:24.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:24:0) after 1m0s
[02:29:25.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:24:0)
[02:29:25.057] Timer tick, asking Buildbucket for the build status
[02:29:26.162] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:26.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:25:0) after 1m0s
[02:30:26.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:25:0)
[02:30:26.208] Timer tick, asking Buildbucket for the build status
[02:30:26.262] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:26.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:26:0) after 1m0s
[02:31:26.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:26:0)
[02:31:26.256] Timer tick, asking Buildbucket for the build status
[02:31:26.433] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:26.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:27:0) after 1m0s
[02:32:26.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:27:0)
[02:32:26.658] Timer tick, asking Buildbucket for the build status
[02:32:26.979] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:26.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:28:0) after 1m0s
[02:33:27.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:28:0)
[02:33:27.034] Timer tick, asking Buildbucket for the build status
[02:33:27.139] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:27.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:29:0) after 1m0s
[02:34:27.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:29:0)
[02:34:27.269] Timer tick, asking Buildbucket for the build status
[02:34:27.375] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:27.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:30:0) after 1m0s
[02:35:27.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:30:0)
[02:35:27.513] Timer tick, asking Buildbucket for the build status
[02:35:27.627] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:27.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:31:0) after 1m0s
[02:36:27.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:31:0)
[02:36:27.900] Timer tick, asking Buildbucket for the build status
[02:36:28.028] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:28.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:32:0) after 1m0s
[02:37:28.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:32:0)
[02:37:28.176] Timer tick, asking Buildbucket for the build status
[02:37:28.295] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:28.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:33:0) after 1m0s
[02:38:28.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:33:0)
[02:38:28.671] Timer tick, asking Buildbucket for the build status
[02:38:28.807] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:28.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:34:0) after 1m0s
[02:39:28.853] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:34:0)
[02:39:28.853] Timer tick, asking Buildbucket for the build status
[02:39:28.954] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:28.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:35:0) after 1m0s
[02:40:29.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:35:0)
[02:40:29.035] Timer tick, asking Buildbucket for the build status
[02:40:29.136] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:29.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:36:0) after 1m0s
[02:41:29.285] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:36:0)
[02:41:29.285] Timer tick, asking Buildbucket for the build status
[02:41:29.395] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:29.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:37:0) after 1m0s
[02:42:29.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:37:0)
[02:42:29.454] Timer tick, asking Buildbucket for the build status
[02:42:29.587] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:29.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:38:0) after 1m0s
[02:43:29.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:38:0)
[02:43:29.744] Timer tick, asking Buildbucket for the build status
[02:43:30.047] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:30.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:39:0) after 1m0s
[02:44:30.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:39:0)
[02:44:30.483] Timer tick, asking Buildbucket for the build status
[02:44:30.616] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:30.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:40:0) after 1m0s
[02:45:30.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:40:0)
[02:45:30.929] Timer tick, asking Buildbucket for the build status
[02:45:31.179] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:31.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:41:0) after 1m0s
[02:46:31.232] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:41:0)
[02:46:31.232] Timer tick, asking Buildbucket for the build status
[02:46:31.424] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:31.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:42:0) after 1m0s
[02:47:31.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:42:0)
[02:47:31.618] Timer tick, asking Buildbucket for the build status
[02:47:31.848] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:31.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:43:0) after 1m0s
[02:48:31.995] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:43:0)
[02:48:31.995] Timer tick, asking Buildbucket for the build status
[02:48:32.145] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:32.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:44:0) after 1m0s
[02:49:32.188] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:44:0)
[02:49:32.188] Timer tick, asking Buildbucket for the build status
[02:49:32.285] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:32.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:45:0) after 1m0s
[02:50:32.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:45:0)
[02:50:32.754] Timer tick, asking Buildbucket for the build status
[02:50:32.869] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:32.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:46:0) after 1m0s
[02:51:33.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:46:0)
[02:51:33.350] Timer tick, asking Buildbucket for the build status
[02:51:33.499] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:33.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:47:0) after 1m0s
[02:52:33.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:47:0)
[02:52:33.573] Timer tick, asking Buildbucket for the build status
[02:52:33.710] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:33.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:48:0) after 1m0s
[02:53:33.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:48:0)
[02:53:33.872] Timer tick, asking Buildbucket for the build status
[02:53:33.963] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:33.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:49:0) after 1m0s
[02:54:34.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:49:0)
[02:54:34.066] Timer tick, asking Buildbucket for the build status
[02:54:34.175] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:34.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:50:0) after 1m0s
[02:55:34.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:50:0)
[02:55:34.524] Timer tick, asking Buildbucket for the build status
[02:55:34.724] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:34.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:51:0) after 1m0s
[02:56:34.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:51:0)
[02:56:34.819] Timer tick, asking Buildbucket for the build status
[02:56:35.013] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:35.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:52:0) after 1m0s
[02:57:35.280] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:52:0)
[02:57:35.280] Timer tick, asking Buildbucket for the build status
[02:57:35.524] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:35.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:53:0) after 1m0s
[02:58:35.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:53:0)
[02:58:35.627] Timer tick, asking Buildbucket for the build status
[02:58:35.731] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:35.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:54:0) after 1m0s
[02:59:35.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:54:0)
[02:59:35.935] Timer tick, asking Buildbucket for the build status
[02:59:36.039] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:36.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:55:0) after 1m0s
[03:00:36.149] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:55:0)
[03:00:36.149] Timer tick, asking Buildbucket for the build status
[03:00:36.260] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:36.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:56:0) after 1m0s
[03:01:36.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:56:0)
[03:01:36.287] Timer tick, asking Buildbucket for the build status
[03:01:36.407] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:36.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:57:0) after 1m0s
[03:02:36.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:57:0)
[03:02:36.651] Timer tick, asking Buildbucket for the build status
[03:02:36.821] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:36.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:58:0) after 1m0s
[03:03:36.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:58:0)
[03:03:36.879] Timer tick, asking Buildbucket for the build status
[03:03:37.133] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:37.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:59:0) after 1m0s
[03:04:37.359] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:59:0)
[03:04:37.359] Timer tick, asking Buildbucket for the build status
[03:04:37.456] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:37.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:60:0) after 1m0s
[03:05:37.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:60:0)
[03:05:37.490] Timer tick, asking Buildbucket for the build status
[03:05:37.887] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:37.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:61:0) after 1m0s
[03:06:38.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:61:0)
[03:06:38.112] Timer tick, asking Buildbucket for the build status
[03:06:38.205] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:38.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:62:0) after 1m0s
[03:07:38.251] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:62:0)
[03:07:38.251] Timer tick, asking Buildbucket for the build status
[03:07:38.388] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:38.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:63:0) after 1m0s
[03:08:38.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:63:0)
[03:08:38.451] Timer tick, asking Buildbucket for the build status
[03:08:38.541] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:38.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:64:0) after 1m0s
[03:09:38.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:64:0)
[03:09:38.618] Timer tick, asking Buildbucket for the build status
[03:09:38.722] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:38.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:65:0) after 1m0s
[03:10:38.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:65:0)
[03:10:38.772] Timer tick, asking Buildbucket for the build status
[03:10:39.697] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:39.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:66:0) after 1m0s
[03:11:39.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:66:0)
[03:11:39.689] Timer tick, asking Buildbucket for the build status
[03:11:39.768] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:39.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:67:0) after 1m0s
[03:12:39.882] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:67:0)
[03:12:39.882] Timer tick, asking Buildbucket for the build status
[03:12:40.030] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:40.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:68:0) after 1m0s
[03:13:41.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:68:0)
[03:13:41.969] Timer tick, asking Buildbucket for the build status
[03:13:43.520] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:43.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:69:0) after 1m0s
[03:14:44.246] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:69:0)
[03:14:44.246] Timer tick, asking Buildbucket for the build status
[03:14:44.354] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:44.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:70:0) after 1m0s
[03:15:44.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:70:0)
[03:15:44.594] Timer tick, asking Buildbucket for the build status
[03:15:44.823] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:44.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:71:0) after 1m0s
[03:16:45.071] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:71:0)
[03:16:45.071] Timer tick, asking Buildbucket for the build status
[03:16:45.154] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:45.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:72:0) after 1m0s
[03:17:45.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:72:0)
[03:17:45.674] Timer tick, asking Buildbucket for the build status
[03:17:45.920] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:45.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:73:0) after 1m0s
[03:18:46.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:73:0)
[03:18:46.118] Timer tick, asking Buildbucket for the build status
[03:18:46.219] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:46.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:74:0) after 1m0s
[03:19:46.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:74:0)
[03:19:46.288] Timer tick, asking Buildbucket for the build status
[03:19:46.412] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:46.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:75:0) after 1m0s
[03:20:46.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:75:0)
[03:20:46.770] Timer tick, asking Buildbucket for the build status
[03:20:46.850] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:46.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:76:0) after 1m0s
[03:21:46.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:76:0)
[03:21:46.952] Timer tick, asking Buildbucket for the build status
[03:21:47.023] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:47.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:77:0) after 1m0s
[03:22:47.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:77:0)
[03:22:47.258] Timer tick, asking Buildbucket for the build status
[03:22:47.386] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:47.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:78:0) after 1m0s
[03:23:47.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:78:0)
[03:23:47.930] Timer tick, asking Buildbucket for the build status
[03:23:48.088] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:48.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:79:0) after 1m0s
[03:24:48.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:79:0)
[03:24:48.592] Timer tick, asking Buildbucket for the build status
[03:24:48.685] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:48.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:80:0) after 1m0s
[03:25:49.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:80:0)
[03:25:49.259] Timer tick, asking Buildbucket for the build status
[03:25:49.650] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:49.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:81:0) after 1m0s
[03:26:49.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:81:0)
[03:26:49.785] Timer tick, asking Buildbucket for the build status
[03:26:50.190] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:50.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:82:0) after 1m0s
[03:27:50.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:82:0)
[03:27:50.482] Timer tick, asking Buildbucket for the build status
[03:27:50.641] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:50.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:83:0) after 1m0s
[03:28:50.996] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:83:0)
[03:28:50.996] Timer tick, asking Buildbucket for the build status
[03:28:51.093] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:51.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:84:0) after 1m0s
[03:29:51.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:84:0)
[03:29:51.172] Timer tick, asking Buildbucket for the build status
[03:29:51.252] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:51.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:85:0) after 1m0s
[03:30:51.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:85:0)
[03:30:51.326] Timer tick, asking Buildbucket for the build status
[03:30:51.437] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:51.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:86:0) after 1m0s
[03:31:51.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:86:0)
[03:31:51.529] Timer tick, asking Buildbucket for the build status
[03:31:51.680] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:51.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:87:0) after 1m0s
[03:32:51.800] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:87:0)
[03:32:51.800] Timer tick, asking Buildbucket for the build status
[03:32:51.972] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:51.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:88:0) after 1m0s
[03:33:52.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:88:0)
[03:33:52.970] Timer tick, asking Buildbucket for the build status
[03:33:53.168] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:53.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:89:0) after 1m0s
[03:34:53.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:89:0)
[03:34:53.272] Timer tick, asking Buildbucket for the build status
[03:34:53.357] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:53.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:90:0) after 1m0s
[03:35:53.375] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:90:0)
[03:35:53.375] Timer tick, asking Buildbucket for the build status
[03:35:53.488] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:53.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:91:0) after 1m0s
[03:36:53.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:91:0)
[03:36:53.688] Timer tick, asking Buildbucket for the build status
[03:36:53.791] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:53.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:92:0) after 1m0s
[03:37:54.119] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:92:0)
[03:37:54.124] Timer tick, asking Buildbucket for the build status
[03:37:55.602] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:55.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:93:0) after 1m0s
[03:38:55.661] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:93:0)
[03:38:55.661] Timer tick, asking Buildbucket for the build status
[03:38:57.844] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:57.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:94:0) after 1m0s
[03:39:57.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:94:0)
[03:39:57.938] Timer tick, asking Buildbucket for the build status
[03:39:58.064] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:58.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:95:0) after 1m0s
[03:40:58.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:95:0)
[03:40:58.243] Timer tick, asking Buildbucket for the build status
[03:40:58.352] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:58.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:96:0) after 1m0s
[03:41:59.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:96:0)
[03:41:59.271] Timer tick, asking Buildbucket for the build status
[03:41:59.424] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:59.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:97:0) after 1m0s
[03:42:59.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:97:0)
[03:42:59.818] Timer tick, asking Buildbucket for the build status
[03:43:00.228] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:00.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:98:0) after 1m0s
[03:44:00.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:98:0)
[03:44:00.302] Timer tick, asking Buildbucket for the build status
[03:44:00.398] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:00.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:99:0) after 1m0s
[03:45:01.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:99:0)
[03:45:01.037] Timer tick, asking Buildbucket for the build status
[03:45:01.116] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:01.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:100:0) after 1m0s
[03:46:01.188] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:100:0)
[03:46:01.188] Timer tick, asking Buildbucket for the build status
[03:46:01.390] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:01.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:101:0) after 1m0s
[03:47:02.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:101:0)
[03:47:02.164] Timer tick, asking Buildbucket for the build status
[03:47:02.518] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:02.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:102:0) after 1m0s
[03:48:03.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:102:0)
[03:48:03.354] Timer tick, asking Buildbucket for the build status
[03:48:03.579] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:03.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:103:0) after 1m0s
[03:49:03.659] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:103:0)
[03:49:03.659] Timer tick, asking Buildbucket for the build status
[03:49:03.729] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:03.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:104:0) after 1m0s
[03:50:03.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:104:0)
[03:50:03.845] Timer tick, asking Buildbucket for the build status
[03:50:03.937] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:03.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:105:0) after 1m0s
[03:51:03.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:105:0)
[03:51:03.958] Timer tick, asking Buildbucket for the build status
[03:51:04.056] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:04.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:106:0) after 1m0s
[03:52:04.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:106:0)
[03:52:04.397] Timer tick, asking Buildbucket for the build status
[03:52:04.504] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:04.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:107:0) after 1m0s
[03:53:04.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:107:0)
[03:53:04.687] Timer tick, asking Buildbucket for the build status
[03:53:04.888] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:04.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:108:0) after 1m0s
[03:54:05.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:108:0)
[03:54:05.386] Timer tick, asking Buildbucket for the build status
[03:54:05.491] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:05.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:109:0) after 1m0s
[03:55:06.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:109:0)
[03:55:06.125] Timer tick, asking Buildbucket for the build status
[03:55:06.408] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:06.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:110:0) after 1m0s
[03:56:06.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:110:0)
[03:56:06.488] Timer tick, asking Buildbucket for the build status
[03:56:06.849] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:06.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:111:0) after 1m0s
[03:57:07.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:111:0)
[03:57:07.306] Timer tick, asking Buildbucket for the build status
[03:57:07.479] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:07.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:112:0) after 1m0s
[03:58:07.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:112:0)
[03:58:07.620] Timer tick, asking Buildbucket for the build status
[03:58:07.760] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:07.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:113:0) after 1m0s
[03:59:07.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:113:0)
[03:59:07.863] Timer tick, asking Buildbucket for the build status
[03:59:08.070] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:08.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:114:0) after 1m0s
[04:00:08.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:114:0)
[04:00:08.125] Timer tick, asking Buildbucket for the build status
[04:00:08.202] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:08.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:115:0) after 1m0s
[04:01:08.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:115:0)
[04:01:08.414] Timer tick, asking Buildbucket for the build status
[04:01:08.503] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:08.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:116:0) after 1m0s
[04:02:08.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:116:0)
[04:02:08.615] Timer tick, asking Buildbucket for the build status
[04:02:08.753] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:08.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:117:0) after 1m0s
[04:03:09.024] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:117:0)
[04:03:09.024] Timer tick, asking Buildbucket for the build status
[04:03:09.879] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:09.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:118:0) after 1m0s
[04:04:10.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:118:0)
[04:04:10.283] Timer tick, asking Buildbucket for the build status
[04:04:10.355] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:10.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:119:0) after 1m0s
[04:05:10.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:119:0)
[04:05:10.477] Timer tick, asking Buildbucket for the build status
[04:05:10.571] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:10.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:120:0) after 1m0s
[04:06:10.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:120:0)
[04:06:10.616] Timer tick, asking Buildbucket for the build status
[04:06:10.979] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:10.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:121:0) after 1m0s
[04:07:10.986] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:121:0)
[04:07:10.986] Timer tick, asking Buildbucket for the build status
[04:07:11.446] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:11.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:122:0) after 1m0s
[04:08:11.835] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:122:0)
[04:08:11.835] Timer tick, asking Buildbucket for the build status
[04:08:12.111] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:12.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:123:0) after 1m0s
[04:09:12.153] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:123:0)
[04:09:12.153] Timer tick, asking Buildbucket for the build status
[04:09:12.507] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:12.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:124:0) after 1m0s
[04:10:12.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:124:0)
[04:10:12.584] Timer tick, asking Buildbucket for the build status
[04:10:12.710] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:12.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:125:0) after 1m0s
[04:11:12.959] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:125:0)
[04:11:12.959] Timer tick, asking Buildbucket for the build status
[04:11:13.117] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:13.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:126:0) after 1m0s
[04:12:13.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:126:0)
[04:12:13.619] Timer tick, asking Buildbucket for the build status
[04:12:13.712] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:13.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:127:0) after 1m0s
[04:13:13.743] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:127:0)
[04:13:13.743] Timer tick, asking Buildbucket for the build status
[04:13:13.899] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:13.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:128:0) after 1m0s
[04:14:13.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:128:0)
[04:14:13.938] Timer tick, asking Buildbucket for the build status
[04:14:14.089] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:14.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:129:0) after 1m0s
[04:15:14.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:129:0)
[04:15:14.164] Timer tick, asking Buildbucket for the build status
[04:15:14.385] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:14.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:130:0) after 1m0s
[04:16:15.202] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:130:0)
[04:16:15.202] Timer tick, asking Buildbucket for the build status
[04:16:15.361] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:15.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:131:0) after 1m0s
[04:17:15.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:131:0)
[04:17:15.437] Timer tick, asking Buildbucket for the build status
[04:17:15.515] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:15.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:132:0) after 1m0s
[04:18:15.559] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:132:0)
[04:18:15.560] Timer tick, asking Buildbucket for the build status
[04:18:15.782] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:15.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:133:0) after 1m0s
[04:19:15.824] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:133:0)
[04:19:15.824] Timer tick, asking Buildbucket for the build status
[04:19:15.932] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:15.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:134:0) after 1m0s
[04:20:16.496] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:134:0)
[04:20:16.496] Timer tick, asking Buildbucket for the build status
[04:20:16.612] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:16.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:135:0) after 1m0s
[04:21:16.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:135:0)
[04:21:16.896] Timer tick, asking Buildbucket for the build status
[04:21:16.993] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:16.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:136:0) after 1m0s
[04:22:17.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:136:0)
[04:22:17.113] Timer tick, asking Buildbucket for the build status
[04:22:17.223] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:17.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:137:0) after 1m0s
[04:23:17.370] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:137:0)
[04:23:17.370] Timer tick, asking Buildbucket for the build status
[04:23:18.070] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:18.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:138:0) after 1m0s
[04:24:18.226] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:138:0)
[04:24:18.226] Timer tick, asking Buildbucket for the build status
[04:24:18.309] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:18.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:139:0) after 1m0s
[04:25:18.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:139:0)
[04:25:18.416] Timer tick, asking Buildbucket for the build status
[04:25:18.526] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:18.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:140:0) after 1m0s
[04:26:18.670] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:140:0)
[04:26:18.670] Timer tick, asking Buildbucket for the build status
[04:26:18.792] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:18.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:141:0) after 1m0s
[04:27:18.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:141:0)
[04:27:18.941] Timer tick, asking Buildbucket for the build status
[04:27:19.284] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:19.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:142:0) after 1m0s
[04:28:19.311] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:142:0)
[04:28:19.311] Timer tick, asking Buildbucket for the build status
[04:28:19.424] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:19.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:143:0) after 1m0s
[04:29:19.549] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:143:0)
[04:29:19.549] Timer tick, asking Buildbucket for the build status
[04:29:19.680] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:19.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:144:0) after 1m0s
[04:30:20.002] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:144:0)
[04:30:20.002] Timer tick, asking Buildbucket for the build status
[04:30:20.718] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:20.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:145:0) after 1m0s
[04:31:20.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:145:0)
[04:31:20.745] Timer tick, asking Buildbucket for the build status
[04:31:20.981] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:20.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:146:0) after 1m0s
[04:32:21.055] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:146:0)
[04:32:21.055] Timer tick, asking Buildbucket for the build status
[04:32:21.183] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:21.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:147:0) after 1m0s
[04:33:21.203] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:147:0)
[04:33:21.203] Timer tick, asking Buildbucket for the build status
[04:33:21.271] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:21.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:148:0) after 1m0s
[04:34:21.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:148:0)
[04:34:21.367] Timer tick, asking Buildbucket for the build status
[04:34:21.454] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:21.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:149:0) after 1m0s
[04:35:21.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:149:0)
[04:35:21.704] Timer tick, asking Buildbucket for the build status
[04:35:22.993] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:22.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:150:0) after 1m0s
[04:36:23.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:150:0)
[04:36:23.102] Timer tick, asking Buildbucket for the build status
[04:36:23.185] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:23.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:151:0) after 1m0s
[04:37:23.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:151:0)
[04:37:23.225] Timer tick, asking Buildbucket for the build status
[04:37:23.362] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:23.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:152:0) after 1m0s
[04:38:23.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:152:0)
[04:38:23.369] Timer tick, asking Buildbucket for the build status
[04:38:23.589] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:23.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:153:0) after 1m0s
[04:39:23.628] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:153:0)
[04:39:23.628] Timer tick, asking Buildbucket for the build status
[04:39:23.720] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:23.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:154:0) after 1m0s
[04:40:23.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:154:0)
[04:40:23.754] Timer tick, asking Buildbucket for the build status
[04:40:24.482] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:24.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:155:0) after 1m0s
[04:41:24.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:155:0)
[04:41:24.509] Timer tick, asking Buildbucket for the build status
[04:41:24.602] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:24.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:156:0) after 1m0s
[04:42:24.624] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:156:0)
[04:42:24.624] Timer tick, asking Buildbucket for the build status
[04:42:24.824] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:24.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:157:0) after 1m0s
[04:43:24.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:157:0)
[04:43:24.942] Timer tick, asking Buildbucket for the build status
[04:43:25.067] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:25.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:158:0) after 1m0s
[04:44:25.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:158:0)
[04:44:25.117] Timer tick, asking Buildbucket for the build status
[04:44:25.751] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:25.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:159:0) after 1m0s
[04:45:25.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:159:0)
[04:45:25.781] Timer tick, asking Buildbucket for the build status
[04:45:25.858] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:25.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:160:0) after 1m0s
[04:46:25.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:160:0)
[04:46:25.890] Timer tick, asking Buildbucket for the build status
[04:46:25.992] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:25.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:161:0) after 1m0s
[04:47:26.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:161:0)
[04:47:26.104] Timer tick, asking Buildbucket for the build status
[04:47:26.255] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:26.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:162:0) after 1m0s
[04:48:26.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:162:0)
[04:48:26.667] Timer tick, asking Buildbucket for the build status
[04:48:26.848] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:26.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:163:0) after 1m0s
[04:49:27.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:163:0)
[04:49:27.262] Timer tick, asking Buildbucket for the build status
[04:49:27.399] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:27.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:164:0) after 1m0s
[04:50:27.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:164:0)
[04:50:27.672] Timer tick, asking Buildbucket for the build status
[04:50:27.753] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:27.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:165:0) after 1m0s
[04:51:27.874] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:165:0)
[04:51:27.874] Timer tick, asking Buildbucket for the build status
[04:51:27.979] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:27.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:166:0) after 1m0s
[04:52:28.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:166:0)
[04:52:28.130] Timer tick, asking Buildbucket for the build status
[04:52:28.481] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:28.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:167:0) after 1m0s
[04:53:28.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:167:0)
[04:53:28.822] Timer tick, asking Buildbucket for the build status
[04:53:29.178] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:29.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:168:0) after 1m0s
[04:54:29.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:168:0)
[04:54:29.202] Timer tick, asking Buildbucket for the build status
[04:54:29.534] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:29.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:169:0) after 1m0s
[04:55:29.559] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:169:0)
[04:55:29.559] Timer tick, asking Buildbucket for the build status
[04:55:29.946] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:29.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:170:0) after 1m0s
[04:56:30.082] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:170:0)
[04:56:30.082] Timer tick, asking Buildbucket for the build status
[04:56:30.141] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:30.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:171:0) after 1m0s
[04:57:30.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:171:0)
[04:57:30.239] Timer tick, asking Buildbucket for the build status
[04:57:30.521] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:30.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:172:0) after 1m0s
[04:58:30.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:172:0)
[04:58:30.595] Timer tick, asking Buildbucket for the build status
[04:58:30.704] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:30.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:173:0) after 1m0s
[04:59:30.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:173:0)
[04:59:30.773] Timer tick, asking Buildbucket for the build status
[04:59:30.864] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:30.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:174:0) after 1m0s
[05:00:30.977] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:174:0)
[05:00:30.977] Timer tick, asking Buildbucket for the build status
[05:00:31.048] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:31.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:175:0) after 1m0s
[05:01:31.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:175:0)
[05:01:31.165] Timer tick, asking Buildbucket for the build status
[05:01:31.257] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:31.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:176:0) after 1m0s
[05:02:31.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:176:0)
[05:02:31.579] Timer tick, asking Buildbucket for the build status
[05:02:31.910] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:31.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:177:0) after 1m0s
[05:03:31.959] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:177:0)
[05:03:31.959] Timer tick, asking Buildbucket for the build status
[05:03:32.089] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:32.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:178:0) after 1m0s
[05:04:32.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:178:0)
[05:04:32.126] Timer tick, asking Buildbucket for the build status
[05:04:32.197] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:32.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:179:0) after 1m0s
[05:05:32.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:179:0)
[05:05:32.223] Timer tick, asking Buildbucket for the build status
[05:05:32.305] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:32.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:180:0) after 1m0s
[05:06:32.336] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:180:0)
[05:06:32.336] Timer tick, asking Buildbucket for the build status
[05:06:32.565] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:32.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:181:0) after 1m0s
[05:07:32.631] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:181:0)
[05:07:32.631] Timer tick, asking Buildbucket for the build status
[05:07:32.922] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:32.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:182:0) after 1m0s
[05:08:33.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:182:0)
[05:08:33.016] Timer tick, asking Buildbucket for the build status
[05:08:33.164] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:33.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:183:0) after 1m0s
[05:09:33.762] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:183:0)
[05:09:33.762] Timer tick, asking Buildbucket for the build status
[05:09:34.238] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:34.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:184:0) after 1m0s
[05:10:34.301] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:184:0)
[05:10:34.301] Timer tick, asking Buildbucket for the build status
[05:10:34.606] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:34.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:185:0) after 1m0s
[05:11:34.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:185:0)
[05:11:34.697] Timer tick, asking Buildbucket for the build status
[05:11:35.444] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:35.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:186:0) after 1m0s
[05:12:35.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:186:0)
[05:12:35.543] Timer tick, asking Buildbucket for the build status
[05:12:35.701] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:35.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:187:0) after 1m0s
[05:13:35.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:187:0)
[05:13:35.814] Timer tick, asking Buildbucket for the build status
[05:13:35.979] Build 8870307656035809536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:35.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9035737718148153856:188:0) after 1m0s
[05:14:19.583] Received PubSub notification, asking Buildbucket for the build status
[05:14:19.664] Build 8870307656035809536: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:14:19.664] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599023657801618",
"created_by": "project:chromium",
"created_ts": "1599012432024016",
"id": "8870307656035809536",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ec2e2f992dfa2f17fea5083267850ea02313046d\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-2-3k06\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 484, \"got_angle_revision\": \"6eeb3d8bf667e0f069f40580b2561372fdaca8c0\", \"got_dawn_revision\": \"f114a68b8f9b19dce6c8dddf3070e985ef925faa\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"ec2e2f992dfa2f17fea5083267850ea02313046d\", \"got_revision_cp\": \"refs/heads/master@{#803735}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"2d5bbdc4d9de7bf12bb3da9cf87ca9c353ab6523\", \"got_v8_revision\": \"964006ef247338458c9770f654b085a9721ca6b2\", \"got_v8_revision_cp\": \"refs/heads/8.7.46@{#1}\", \"got_webrtc_revision\": \"2597a1b22c0a906023d7cb204a91bb49c2b96c64\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32025}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ec2e2f992dfa2f17fea5083267850ea02313046d\"}, \"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-2-3k06\"], \"image\": [\"chrome-win10-1703-20081700-d9392568d25\"], \"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\": [\"5507-6e8d655\"], \"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": "1599012469946769",
"status": "COMPLETED",
"status_changed_ts": "1599023658475510",
"tags": [
"build_address:luci.chromium.ci/win-official/484",
"builder:win-official",
"buildset:commit/git/ec2e2f992dfa2f17fea5083267850ea02313046d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ec2e2f992dfa2f17fea5083267850ea02313046d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035737718148153856",
"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/8870307656035809536/+/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:4e6562f38d1ffa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1599023658487858",
"url": "https://ci.chromium.org/b/8870307656035809536",
"utcnow_ts": "1599023659656598"
}
}
[05:14:19.664] Invocation finished in 3h7m9.038004826s with status SUCCEEDED