[02:09:10.086] New invocation is queued and will start shortly
[02:09:11.177] Starting the invocation (attempt 1)
[02:09:11.250] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:09:11.251] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:09:11.251] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9082395030608391808",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"18bc982c0669644fb76908b71568046dcd7fa8c7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9082395030608391808",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/18bc982c0669644fb76908b71568046dcd7fa8c7",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/18bc982c0669644fb76908b71568046dcd7fa8c7",
"gitiles_ref:refs/heads/master"
]
}
[02:09:12.365] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1554516551358831",
"id": "8916964968601310320",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"18bc982c0669644fb76908b71568046dcd7fa8c7\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554516552059082",
"tags": [
"builder:clang",
"buildset:commit/git/18bc982c0669644fb76908b71568046dcd7fa8c7",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/18bc982c0669644fb76908b71568046dcd7fa8c7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082395030608391808",
"scheduler_job_id:fuchsia/prod-clang",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8916964968601310320/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:buildbucket_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1554516552062120",
"url": "https://ci.chromium.org/b/8916964968601310320",
"utcnow_ts": "1554516552331363"
}
}
[02:09:12.365] Task URL: https://ci.chromium.org/b/8916964968601310320
[02:09:12.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:2:0) after 1m0s
[02:09:24.572] Received PubSub notification, asking Buildbucket for the build status
[02:09:24.698] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:12.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:2:0)
[02:10:12.394] Timer tick, asking Buildbucket for the build status
[02:10:12.533] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:12.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:4:0) after 1m0s
[02:11:12.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:4:0)
[02:11:12.553] Timer tick, asking Buildbucket for the build status
[02:11:12.630] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:12.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:5:0) after 1m0s
[02:12:12.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:5:0)
[02:12:12.674] Timer tick, asking Buildbucket for the build status
[02:12:12.750] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:12.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:6:0) after 1m0s
[02:13:12.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:6:0)
[02:13:12.757] Timer tick, asking Buildbucket for the build status
[02:13:12.876] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:12.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:7:0) after 1m0s
[02:14:12.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:7:0)
[02:14:12.908] Timer tick, asking Buildbucket for the build status
[02:14:12.969] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:12.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:8:0) after 1m0s
[02:15:12.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:8:0)
[02:15:12.986] Timer tick, asking Buildbucket for the build status
[02:15:13.160] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:13.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:9:0) after 1m0s
[02:16:13.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:9:0)
[02:16:13.164] Timer tick, asking Buildbucket for the build status
[02:16:13.240] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:13.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:10:0) after 1m0s
[02:17:13.258] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:10:0)
[02:17:13.258] Timer tick, asking Buildbucket for the build status
[02:17:13.311] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:13.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:11:0) after 1m0s
[02:18:13.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:11:0)
[02:18:13.282] Timer tick, asking Buildbucket for the build status
[02:18:13.360] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:13.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:12:0) after 1m0s
[02:19:13.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:12:0)
[02:19:13.378] Timer tick, asking Buildbucket for the build status
[02:19:13.503] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:13.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:13:0) after 1m0s
[02:20:13.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:13:0)
[02:20:13.523] Timer tick, asking Buildbucket for the build status
[02:20:13.594] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:13.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:14:0) after 1m0s
[02:21:13.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:14:0)
[02:21:13.614] Timer tick, asking Buildbucket for the build status
[02:21:13.713] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:13.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:15:0) after 1m0s
[02:22:13.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:15:0)
[02:22:13.792] Timer tick, asking Buildbucket for the build status
[02:22:13.863] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:13.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:16:0) after 1m0s
[02:23:13.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:16:0)
[02:23:13.882] Timer tick, asking Buildbucket for the build status
[02:23:13.932] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:13.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:17:0) after 1m0s
[02:24:13.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:17:0)
[02:24:13.948] Timer tick, asking Buildbucket for the build status
[02:24:14.007] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:14.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:18:0) after 1m0s
[02:25:14.039] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:18:0)
[02:25:14.039] Timer tick, asking Buildbucket for the build status
[02:25:14.110] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:14.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:19:0) after 1m0s
[02:26:14.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:19:0)
[02:26:14.128] Timer tick, asking Buildbucket for the build status
[02:26:14.196] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:14.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:20:0) after 1m0s
[02:27:14.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:20:0)
[02:27:14.214] Timer tick, asking Buildbucket for the build status
[02:27:14.292] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:14.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:21:0) after 1m0s
[02:28:14.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:21:0)
[02:28:14.312] Timer tick, asking Buildbucket for the build status
[02:28:14.385] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:14.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:22:0) after 1m0s
[02:29:14.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:22:0)
[02:29:14.406] Timer tick, asking Buildbucket for the build status
[02:29:14.462] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:14.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:23:0) after 1m0s
[02:30:14.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:23:0)
[02:30:14.458] Timer tick, asking Buildbucket for the build status
[02:30:14.517] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:14.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:24:0) after 1m0s
[02:31:14.544] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:24:0)
[02:31:14.544] Timer tick, asking Buildbucket for the build status
[02:31:14.679] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:14.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:25:0) after 1m0s
[02:32:14.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:25:0)
[02:32:14.698] Timer tick, asking Buildbucket for the build status
[02:32:14.797] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:14.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:26:0) after 1m0s
[02:33:14.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:26:0)
[02:33:14.800] Timer tick, asking Buildbucket for the build status
[02:33:14.881] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:14.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:27:0) after 1m0s
[02:34:14.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:27:0)
[02:34:14.902] Timer tick, asking Buildbucket for the build status
[02:34:15.039] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:15.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:28:0) after 1m0s
[02:35:15.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:28:0)
[02:35:15.035] Timer tick, asking Buildbucket for the build status
[02:35:15.154] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:15.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:29:0) after 1m0s
[02:36:15.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:29:0)
[02:36:15.149] Timer tick, asking Buildbucket for the build status
[02:36:15.192] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:15.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:30:0) after 1m0s
[02:37:15.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:30:0)
[02:37:15.210] Timer tick, asking Buildbucket for the build status
[02:37:15.332] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:15.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:31:0) after 1m0s
[02:38:15.329] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:31:0)
[02:38:15.329] Timer tick, asking Buildbucket for the build status
[02:38:15.396] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:15.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:32:0) after 1m0s
[02:39:15.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:32:0)
[02:39:15.395] Timer tick, asking Buildbucket for the build status
[02:39:15.459] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:15.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:33:0) after 1m0s
[02:40:15.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:33:0)
[02:40:15.479] Timer tick, asking Buildbucket for the build status
[02:40:15.666] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:15.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:34:0) after 1m0s
[02:41:15.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:34:0)
[02:41:15.686] Timer tick, asking Buildbucket for the build status
[02:41:15.746] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:15.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:35:0) after 1m0s
[02:42:15.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:35:0)
[02:42:15.764] Timer tick, asking Buildbucket for the build status
[02:42:15.829] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:15.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:36:0) after 1m0s
[02:43:15.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:36:0)
[02:43:15.848] Timer tick, asking Buildbucket for the build status
[02:43:15.998] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:15.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:37:0) after 1m0s
[02:44:16.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:37:0)
[02:44:16.016] Timer tick, asking Buildbucket for the build status
[02:44:16.074] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:16.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:38:0) after 1m0s
[02:45:16.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:38:0)
[02:45:16.114] Timer tick, asking Buildbucket for the build status
[02:45:16.176] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:16.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:39:0) after 1m0s
[02:46:16.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:39:0)
[02:46:16.208] Timer tick, asking Buildbucket for the build status
[02:46:16.390] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:16.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:40:0) after 1m0s
[02:47:16.409] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:40:0)
[02:47:16.409] Timer tick, asking Buildbucket for the build status
[02:47:16.515] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:16.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:41:0) after 1m0s
[02:48:16.532] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:41:0)
[02:48:16.532] Timer tick, asking Buildbucket for the build status
[02:48:16.679] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:16.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:42:0) after 1m0s
[02:49:16.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:42:0)
[02:49:16.707] Timer tick, asking Buildbucket for the build status
[02:49:16.770] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:16.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:43:0) after 1m0s
[02:50:16.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:43:0)
[02:50:16.789] Timer tick, asking Buildbucket for the build status
[02:50:16.845] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:16.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:44:0) after 1m0s
[02:51:16.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:44:0)
[02:51:16.864] Timer tick, asking Buildbucket for the build status
[02:51:16.935] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:16.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:45:0) after 1m0s
[02:52:16.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:45:0)
[02:52:16.950] Timer tick, asking Buildbucket for the build status
[02:52:17.052] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:17.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:46:0) after 1m0s
[02:53:17.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:46:0)
[02:53:17.072] Timer tick, asking Buildbucket for the build status
[02:53:17.138] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:17.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:47:0) after 1m0s
[02:54:17.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:47:0)
[02:54:17.164] Timer tick, asking Buildbucket for the build status
[02:54:17.240] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:17.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:48:0) after 1m0s
[02:55:17.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:48:0)
[02:55:17.264] Timer tick, asking Buildbucket for the build status
[02:55:17.356] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:17.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:49:0) after 1m0s
[02:56:17.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:49:0)
[02:56:17.375] Timer tick, asking Buildbucket for the build status
[02:56:17.764] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:17.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:50:0) after 1m0s
[02:57:17.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:50:0)
[02:57:17.799] Timer tick, asking Buildbucket for the build status
[02:57:18.062] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:18.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:51:0) after 1m0s
[02:58:18.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:51:0)
[02:58:18.063] Timer tick, asking Buildbucket for the build status
[02:58:18.142] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:18.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:52:0) after 1m0s
[02:59:18.165] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:52:0)
[02:59:18.165] Timer tick, asking Buildbucket for the build status
[02:59:18.287] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:18.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:53:0) after 1m0s
[03:00:18.266] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:53:0)
[03:00:18.266] Timer tick, asking Buildbucket for the build status
[03:00:18.406] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:18.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:54:0) after 1m0s
[03:01:18.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:54:0)
[03:01:18.426] Timer tick, asking Buildbucket for the build status
[03:01:18.536] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:18.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:55:0) after 1m0s
[03:02:18.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:55:0)
[03:02:18.558] Timer tick, asking Buildbucket for the build status
[03:02:18.623] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:18.623] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:56:0) after 1m0s
[03:03:18.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:56:0)
[03:03:18.641] Timer tick, asking Buildbucket for the build status
[03:03:18.736] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:18.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:57:0) after 1m0s
[03:04:18.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:57:0)
[03:04:18.755] Timer tick, asking Buildbucket for the build status
[03:04:19.088] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:19.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:58:0) after 1m0s
[03:05:19.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:58:0)
[03:05:19.071] Timer tick, asking Buildbucket for the build status
[03:05:19.126] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:19.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:59:0) after 1m0s
[03:06:19.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:59:0)
[03:06:19.210] Timer tick, asking Buildbucket for the build status
[03:06:19.387] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:19.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:60:0) after 1m0s
[03:07:19.370] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:60:0)
[03:07:19.370] Timer tick, asking Buildbucket for the build status
[03:07:19.502] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:19.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:61:0) after 1m0s
[03:08:19.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:61:0)
[03:08:19.521] Timer tick, asking Buildbucket for the build status
[03:08:19.819] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:19.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:62:0) after 1m0s
[03:09:19.811] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:62:0)
[03:09:19.811] Timer tick, asking Buildbucket for the build status
[03:09:19.891] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:19.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:63:0) after 1m0s
[03:10:19.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:63:0)
[03:10:19.916] Timer tick, asking Buildbucket for the build status
[03:10:19.969] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:19.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:64:0) after 1m0s
[03:11:19.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:64:0)
[03:11:19.999] Timer tick, asking Buildbucket for the build status
[03:11:20.094] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:20.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:65:0) after 1m0s
[03:12:20.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:65:0)
[03:12:20.124] Timer tick, asking Buildbucket for the build status
[03:12:20.201] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:20.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:66:0) after 1m0s
[03:13:20.195] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:66:0)
[03:13:20.195] Timer tick, asking Buildbucket for the build status
[03:13:20.478] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:20.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:67:0) after 1m0s
[03:14:20.494] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:67:0)
[03:14:20.494] Timer tick, asking Buildbucket for the build status
[03:14:20.562] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:20.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:68:0) after 1m0s
[03:15:20.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:68:0)
[03:15:20.561] Timer tick, asking Buildbucket for the build status
[03:15:20.690] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:20.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:69:0) after 1m0s
[03:16:20.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:69:0)
[03:16:20.710] Timer tick, asking Buildbucket for the build status
[03:16:20.854] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:20.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:70:0) after 1m0s
[03:17:20.834] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:70:0)
[03:17:20.834] Timer tick, asking Buildbucket for the build status
[03:17:20.895] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:20.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:71:0) after 1m0s
[03:18:20.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:71:0)
[03:18:20.913] Timer tick, asking Buildbucket for the build status
[03:18:21.080] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:21.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:72:0) after 1m0s
[03:19:21.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:72:0)
[03:19:21.113] Timer tick, asking Buildbucket for the build status
[03:19:21.190] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:21.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:73:0) after 1m0s
[03:20:21.220] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:73:0)
[03:20:21.221] Timer tick, asking Buildbucket for the build status
[03:20:21.275] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:21.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:74:0) after 1m0s
[03:21:21.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:74:0)
[03:21:21.301] Timer tick, asking Buildbucket for the build status
[03:21:21.365] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:21.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:75:0) after 1m0s
[03:22:21.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:75:0)
[03:22:21.391] Timer tick, asking Buildbucket for the build status
[03:22:21.459] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:21.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:76:0) after 1m0s
[03:23:21.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:76:0)
[03:23:21.477] Timer tick, asking Buildbucket for the build status
[03:23:21.708] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:21.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:77:0) after 1m0s
[03:24:21.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:77:0)
[03:24:21.730] Timer tick, asking Buildbucket for the build status
[03:24:22.022] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:22.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:78:0) after 1m0s
[03:25:22.030] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:78:0)
[03:25:22.030] Timer tick, asking Buildbucket for the build status
[03:25:22.094] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:22.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:79:0) after 1m0s
[03:26:22.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:79:0)
[03:26:22.105] Timer tick, asking Buildbucket for the build status
[03:26:22.284] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:22.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:80:0) after 1m0s
[03:27:22.285] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:80:0)
[03:27:22.285] Timer tick, asking Buildbucket for the build status
[03:27:22.382] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:22.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:81:0) after 1m0s
[03:28:22.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:81:0)
[03:28:22.404] Timer tick, asking Buildbucket for the build status
[03:28:22.460] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:22.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:82:0) after 1m0s
[03:29:22.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:82:0)
[03:29:22.479] Timer tick, asking Buildbucket for the build status
[03:29:22.541] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:22.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:83:0) after 1m0s
[03:30:22.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:83:0)
[03:30:22.561] Timer tick, asking Buildbucket for the build status
[03:30:22.678] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:22.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:84:0) after 1m0s
[03:31:22.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:84:0)
[03:31:22.699] Timer tick, asking Buildbucket for the build status
[03:31:22.807] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:22.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:85:0) after 1m0s
[03:32:22.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:85:0)
[03:32:22.832] Timer tick, asking Buildbucket for the build status
[03:32:22.976] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:22.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:86:0) after 1m0s
[03:33:22.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:86:0)
[03:33:22.985] Timer tick, asking Buildbucket for the build status
[03:33:23.043] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:23.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:87:0) after 1m0s
[03:34:23.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:87:0)
[03:34:23.063] Timer tick, asking Buildbucket for the build status
[03:34:23.284] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:23.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:88:0) after 1m0s
[03:35:23.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:88:0)
[03:35:23.304] Timer tick, asking Buildbucket for the build status
[03:35:23.366] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:23.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:89:0) after 1m0s
[03:36:23.370] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:89:0)
[03:36:23.370] Timer tick, asking Buildbucket for the build status
[03:36:23.499] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:23.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:90:0) after 1m0s
[03:37:23.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:90:0)
[03:37:23.525] Timer tick, asking Buildbucket for the build status
[03:37:23.696] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:23.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:91:0) after 1m0s
[03:38:23.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:91:0)
[03:38:23.728] Timer tick, asking Buildbucket for the build status
[03:38:23.809] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:23.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:92:0) after 1m0s
[03:39:23.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:92:0)
[03:39:23.828] Timer tick, asking Buildbucket for the build status
[03:39:23.883] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:23.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:93:0) after 1m0s
[03:40:23.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:93:0)
[03:40:23.942] Timer tick, asking Buildbucket for the build status
[03:40:24.015] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:24.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:94:0) after 1m0s
[03:41:24.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:94:0)
[03:41:24.036] Timer tick, asking Buildbucket for the build status
[03:41:24.186] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:24.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:95:0) after 1m0s
[03:42:24.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:95:0)
[03:42:24.159] Timer tick, asking Buildbucket for the build status
[03:42:24.226] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:24.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:96:0) after 1m0s
[03:43:24.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:96:0)
[03:43:24.259] Timer tick, asking Buildbucket for the build status
[03:43:24.375] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:24.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:97:0) after 1m0s
[03:44:24.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:97:0)
[03:44:24.398] Timer tick, asking Buildbucket for the build status
[03:44:24.501] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:24.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:98:0) after 1m0s
[03:45:24.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:98:0)
[03:45:24.482] Timer tick, asking Buildbucket for the build status
[03:45:24.546] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:24.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:99:0) after 1m0s
[03:46:24.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:99:0)
[03:46:24.577] Timer tick, asking Buildbucket for the build status
[03:46:24.690] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:24.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:100:0) after 1m0s
[03:47:24.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:100:0)
[03:47:24.683] Timer tick, asking Buildbucket for the build status
[03:47:24.740] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:24.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:101:0) after 1m0s
[03:48:24.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:101:0)
[03:48:24.772] Timer tick, asking Buildbucket for the build status
[03:48:24.919] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:24.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:102:0) after 1m0s
[03:49:24.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:102:0)
[03:49:24.943] Timer tick, asking Buildbucket for the build status
[03:49:25.000] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:25.000] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:103:0) after 1m0s
[03:50:24.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:103:0)
[03:50:24.999] Timer tick, asking Buildbucket for the build status
[03:50:25.191] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:25.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:104:0) after 1m0s
[03:51:25.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:104:0)
[03:51:25.215] Timer tick, asking Buildbucket for the build status
[03:51:25.378] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:25.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:105:0) after 1m0s
[03:52:25.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:105:0)
[03:52:25.397] Timer tick, asking Buildbucket for the build status
[03:52:25.589] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:25.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:106:0) after 1m0s
[03:53:25.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:106:0)
[03:53:25.593] Timer tick, asking Buildbucket for the build status
[03:53:25.689] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:25.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:107:0) after 1m0s
[03:54:25.708] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:107:0)
[03:54:25.708] Timer tick, asking Buildbucket for the build status
[03:54:25.942] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:25.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:108:0) after 1m0s
[03:55:25.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:108:0)
[03:55:25.983] Timer tick, asking Buildbucket for the build status
[03:55:26.080] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:26.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:109:0) after 1m0s
[03:56:26.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:109:0)
[03:56:26.092] Timer tick, asking Buildbucket for the build status
[03:56:26.160] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:26.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:110:0) after 1m0s
[03:57:26.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:110:0)
[03:57:26.177] Timer tick, asking Buildbucket for the build status
[03:57:26.321] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:26.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:111:0) after 1m0s
[03:58:26.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:111:0)
[03:58:26.306] Timer tick, asking Buildbucket for the build status
[03:58:26.360] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:26.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:112:0) after 1m0s
[03:59:26.396] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:112:0)
[03:59:26.396] Timer tick, asking Buildbucket for the build status
[03:59:26.503] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:26.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:113:0) after 1m0s
[04:00:26.498] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:113:0)
[04:00:26.498] Timer tick, asking Buildbucket for the build status
[04:00:26.595] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:26.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:114:0) after 1m0s
[04:01:26.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:114:0)
[04:01:26.623] Timer tick, asking Buildbucket for the build status
[04:01:26.695] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:26.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:115:0) after 1m0s
[04:02:26.721] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:115:0)
[04:02:26.721] Timer tick, asking Buildbucket for the build status
[04:02:26.885] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:26.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:116:0) after 1m0s
[04:03:26.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:116:0)
[04:03:26.869] Timer tick, asking Buildbucket for the build status
[04:03:26.932] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:26.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:117:0) after 1m0s
[04:04:26.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:117:0)
[04:04:26.934] Timer tick, asking Buildbucket for the build status
[04:04:26.988] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:26.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:118:0) after 1m0s
[04:05:27.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:118:0)
[04:05:27.017] Timer tick, asking Buildbucket for the build status
[04:05:27.094] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:27.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:119:0) after 1m0s
[04:06:27.120] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:119:0)
[04:06:27.120] Timer tick, asking Buildbucket for the build status
[04:06:27.169] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:27.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:120:0) after 1m0s
[04:07:27.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:120:0)
[04:07:27.190] Timer tick, asking Buildbucket for the build status
[04:07:27.254] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:27.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:121:0) after 1m0s
[04:08:27.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:121:0)
[04:08:27.277] Timer tick, asking Buildbucket for the build status
[04:08:27.399] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:27.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:122:0) after 1m0s
[04:09:27.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:122:0)
[04:09:27.420] Timer tick, asking Buildbucket for the build status
[04:09:27.584] Build 8916964968601310320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:27.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082395030608391808:123:0) after 1m0s
[04:10:00.623] Received PubSub notification, asking Buildbucket for the build status
[04:10:00.666] Build 8916964968601310320: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:10:00.666] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1554523800248955",
"created_by": "project:fuchsia",
"created_ts": "1554516551358831",
"failure_reason": "BUILD_FAILURE",
"id": "8916964968601310320",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"18bc982c0669644fb76908b71568046dcd7fa8c7\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-c-fng8\", \"branch\": \"refs/heads/master\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"path_config\": \"generic\", \"recipe\": \"buildbucket_trigger\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"18bc982c0669644fb76908b71568046dcd7fa8c7\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-c-fng8\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4243-f38882f\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1554516563917864",
"status": "COMPLETED",
"status_changed_ts": "1554516564356151",
"tags": [
"builder:clang",
"buildset:commit/git/18bc982c0669644fb76908b71568046dcd7fa8c7",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/18bc982c0669644fb76908b71568046dcd7fa8c7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082395030608391808",
"scheduler_job_id:fuchsia/prod-clang",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8916964968601310320/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:os:Debian",
"swarming_tag:recipe_name:buildbucket_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:440939b4c3e7a510",
"user_agent:luci-scheduler"
],
"updated_ts": "1554523800307433",
"url": "https://ci.chromium.org/b/8916964968601310320",
"utcnow_ts": "1554523800657872"
}
}
[04:10:00.666] Invocation finished in 2h0m50.605019054s with status FAILED