[17:03:14.319] New invocation is queued and will start shortly
[17:03:15.403] Starting the invocation (attempt 1)
[17:03:15.441] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:03:15.441] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:03:15.441] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079167886720783936",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"e4c5b6d9bddd4cb311794fb69c3d9e9206828bfe\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079167886720783936",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/e4c5b6d9bddd4cb311794fb69c3d9e9206828bfe",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/e4c5b6d9bddd4cb311794fb69c3d9e9206828bfe",
"gitiles_ref:refs/heads/master"
]
}
[17:03:16.867] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557594195563770",
"id": "8913737824751787104",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"e4c5b6d9bddd4cb311794fb69c3d9e9206828bfe\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557594196460114",
"tags": [
"builder:clang",
"buildset:commit/git/e4c5b6d9bddd4cb311794fb69c3d9e9206828bfe",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/e4c5b6d9bddd4cb311794fb69c3d9e9206828bfe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079167886720783936",
"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/8913737824751787104/+/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": "1557594196460209",
"url": "https://ci.chromium.org/b/8913737824751787104",
"utcnow_ts": "1557594196842309"
}
}
[17:03:16.867] Task URL: https://ci.chromium.org/b/8913737824751787104
[17:03:16.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:2:0) after 1m0s
[17:03:50.332] Received PubSub notification, asking Buildbucket for the build status
[17:03:50.712] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:16.903] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:2:0)
[17:04:16.903] Timer tick, asking Buildbucket for the build status
[17:04:17.066] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:17.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:4:0) after 1m0s
[17:05:17.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:4:0)
[17:05:17.103] Timer tick, asking Buildbucket for the build status
[17:05:17.194] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:17.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:5:0) after 1m0s
[17:06:17.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:5:0)
[17:06:17.249] Timer tick, asking Buildbucket for the build status
[17:06:17.395] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:17.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:6:0) after 1m0s
[17:07:17.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:6:0)
[17:07:17.390] Timer tick, asking Buildbucket for the build status
[17:07:17.452] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:17.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:7:0) after 1m0s
[17:08:17.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:7:0)
[17:08:17.509] Timer tick, asking Buildbucket for the build status
[17:08:17.654] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:17.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:8:0) after 1m0s
[17:09:17.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:8:0)
[17:09:17.813] Timer tick, asking Buildbucket for the build status
[17:09:17.922] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:17.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:9:0) after 1m0s
[17:10:17.939] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:9:0)
[17:10:17.939] Timer tick, asking Buildbucket for the build status
[17:10:17.997] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:17.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:10:0) after 1m0s
[17:11:18.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:10:0)
[17:11:18.005] Timer tick, asking Buildbucket for the build status
[17:11:18.059] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:18.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:11:0) after 1m0s
[17:12:18.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:11:0)
[17:12:18.102] Timer tick, asking Buildbucket for the build status
[17:12:18.212] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:18.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:12:0) after 1m0s
[17:13:18.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:12:0)
[17:13:18.236] Timer tick, asking Buildbucket for the build status
[17:13:18.402] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:18.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:13:0) after 1m0s
[17:14:18.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:13:0)
[17:14:18.427] Timer tick, asking Buildbucket for the build status
[17:14:18.488] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:18.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:14:0) after 1m0s
[17:15:18.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:14:0)
[17:15:18.514] Timer tick, asking Buildbucket for the build status
[17:15:18.845] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:18.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:15:0) after 1m0s
[17:16:18.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:15:0)
[17:16:18.913] Timer tick, asking Buildbucket for the build status
[17:16:19.013] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:19.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:16:0) after 1m0s
[17:17:19.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:16:0)
[17:17:19.013] Timer tick, asking Buildbucket for the build status
[17:17:19.754] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:19.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:17:0) after 1m0s
[17:18:19.825] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:17:0)
[17:18:19.825] Timer tick, asking Buildbucket for the build status
[17:18:19.893] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:19.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:18:0) after 1m0s
[17:19:19.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:18:0)
[17:19:19.892] Timer tick, asking Buildbucket for the build status
[17:19:20.129] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:20.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:19:0) after 1m0s
[17:20:20.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:19:0)
[17:20:20.156] Timer tick, asking Buildbucket for the build status
[17:20:20.376] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:20.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:20:0) after 1m0s
[17:21:20.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:20:0)
[17:21:20.406] Timer tick, asking Buildbucket for the build status
[17:21:20.529] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:20.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:21:0) after 1m0s
[17:22:20.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:21:0)
[17:22:20.552] Timer tick, asking Buildbucket for the build status
[17:22:20.709] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:20.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:22:0) after 1m0s
[17:23:20.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:22:0)
[17:23:20.732] Timer tick, asking Buildbucket for the build status
[17:23:20.788] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:20.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:23:0) after 1m0s
[17:24:20.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:23:0)
[17:24:20.789] Timer tick, asking Buildbucket for the build status
[17:24:20.909] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:20.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:24:0) after 1m0s
[17:25:20.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:24:0)
[17:25:20.934] Timer tick, asking Buildbucket for the build status
[17:25:20.979] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:20.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:25:0) after 1m0s
[17:26:21.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:25:0)
[17:26:21.002] Timer tick, asking Buildbucket for the build status
[17:26:21.165] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:21.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:26:0) after 1m0s
[17:27:21.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:26:0)
[17:27:21.188] Timer tick, asking Buildbucket for the build status
[17:27:21.229] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:21.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:27:0) after 1m0s
[17:28:21.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:27:0)
[17:28:21.209] Timer tick, asking Buildbucket for the build status
[17:28:21.347] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:21.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:28:0) after 1m0s
[17:29:21.345] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:28:0)
[17:29:21.345] Timer tick, asking Buildbucket for the build status
[17:29:21.457] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:21.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:29:0) after 1m0s
[17:30:21.637] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:29:0)
[17:30:21.637] Timer tick, asking Buildbucket for the build status
[17:30:21.806] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:21.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:30:0) after 1m0s
[17:31:21.811] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:30:0)
[17:31:21.811] Timer tick, asking Buildbucket for the build status
[17:31:21.881] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:21.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:31:0) after 1m0s
[17:32:21.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:31:0)
[17:32:21.919] Timer tick, asking Buildbucket for the build status
[17:32:22.010] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:22.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:32:0) after 1m0s
[17:33:22.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:32:0)
[17:33:22.029] Timer tick, asking Buildbucket for the build status
[17:33:22.087] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:22.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:33:0) after 1m0s
[17:34:22.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:33:0)
[17:34:22.097] Timer tick, asking Buildbucket for the build status
[17:34:22.366] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:22.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:34:0) after 1m0s
[17:35:22.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:34:0)
[17:35:22.386] Timer tick, asking Buildbucket for the build status
[17:35:22.440] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:22.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:35:0) after 1m0s
[17:36:22.435] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:35:0)
[17:36:22.435] Timer tick, asking Buildbucket for the build status
[17:36:22.547] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:22.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:36:0) after 1m0s
[17:37:22.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:36:0)
[17:37:22.533] Timer tick, asking Buildbucket for the build status
[17:37:22.737] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:22.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:37:0) after 1m0s
[17:38:22.746] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:37:0)
[17:38:22.746] Timer tick, asking Buildbucket for the build status
[17:38:22.809] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:22.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:38:0) after 1m0s
[17:39:22.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:38:0)
[17:39:22.806] Timer tick, asking Buildbucket for the build status
[17:39:22.864] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:22.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:39:0) after 1m0s
[17:40:22.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:39:0)
[17:40:22.884] Timer tick, asking Buildbucket for the build status
[17:40:22.977] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:22.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:40:0) after 1m0s
[17:41:22.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:40:0)
[17:41:22.984] Timer tick, asking Buildbucket for the build status
[17:41:23.394] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:23.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:41:0) after 1m0s
[17:42:23.400] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:41:0)
[17:42:23.400] Timer tick, asking Buildbucket for the build status
[17:42:23.460] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:23.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:42:0) after 1m0s
[17:43:23.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:42:0)
[17:43:23.482] Timer tick, asking Buildbucket for the build status
[17:43:23.574] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:23.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:43:0) after 1m0s
[17:44:23.566] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:43:0)
[17:44:23.566] Timer tick, asking Buildbucket for the build status
[17:44:23.658] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:23.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:44:0) after 1m0s
[17:45:23.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:44:0)
[17:45:23.649] Timer tick, asking Buildbucket for the build status
[17:45:23.724] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:23.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:45:0) after 1m0s
[17:46:23.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:45:0)
[17:46:23.703] Timer tick, asking Buildbucket for the build status
[17:46:23.777] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:23.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:46:0) after 1m0s
[17:47:23.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:46:0)
[17:47:23.809] Timer tick, asking Buildbucket for the build status
[17:47:23.868] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:23.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:47:0) after 1m0s
[17:48:23.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:47:0)
[17:48:23.905] Timer tick, asking Buildbucket for the build status
[17:48:23.976] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:23.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:48:0) after 1m0s
[17:49:23.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:48:0)
[17:49:23.976] Timer tick, asking Buildbucket for the build status
[17:49:24.548] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:24.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:49:0) after 1m0s
[17:50:24.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:49:0)
[17:50:24.571] Timer tick, asking Buildbucket for the build status
[17:50:24.719] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:24.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:50:0) after 1m0s
[17:51:24.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:50:0)
[17:51:24.697] Timer tick, asking Buildbucket for the build status
[17:51:24.868] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:24.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:51:0) after 1m0s
[17:52:24.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:51:0)
[17:52:24.876] Timer tick, asking Buildbucket for the build status
[17:52:25.007] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:25.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:52:0) after 1m0s
[17:53:25.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:52:0)
[17:53:25.037] Timer tick, asking Buildbucket for the build status
[17:53:25.093] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:25.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:53:0) after 1m0s
[17:54:25.141] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:53:0)
[17:54:25.141] Timer tick, asking Buildbucket for the build status
[17:54:25.261] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:25.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:54:0) after 1m0s
[17:55:25.310] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:54:0)
[17:55:25.310] Timer tick, asking Buildbucket for the build status
[17:55:25.457] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:25.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:55:0) after 1m0s
[17:56:25.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:55:0)
[17:56:25.534] Timer tick, asking Buildbucket for the build status
[17:56:26.036] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:26.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:56:0) after 1m0s
[17:57:26.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:56:0)
[17:57:26.029] Timer tick, asking Buildbucket for the build status
[17:57:26.090] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:26.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:57:0) after 1m0s
[17:58:26.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:57:0)
[17:58:26.164] Timer tick, asking Buildbucket for the build status
[17:58:26.291] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:26.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:58:0) after 1m0s
[17:59:26.324] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:58:0)
[17:59:26.324] Timer tick, asking Buildbucket for the build status
[17:59:26.476] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:26.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:59:0) after 1m0s
[18:00:26.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:59:0)
[18:00:26.507] Timer tick, asking Buildbucket for the build status
[18:00:26.777] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:26.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:60:0) after 1m0s
[18:01:26.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:60:0)
[18:01:26.778] Timer tick, asking Buildbucket for the build status
[18:01:26.854] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:26.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:61:0) after 1m0s
[18:02:26.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:61:0)
[18:02:26.886] Timer tick, asking Buildbucket for the build status
[18:02:27.349] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:27.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:62:0) after 1m0s
[18:03:27.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:62:0)
[18:03:27.487] Timer tick, asking Buildbucket for the build status
[18:03:27.591] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:27.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:63:0) after 1m0s
[18:04:27.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:63:0)
[18:04:27.612] Timer tick, asking Buildbucket for the build status
[18:04:27.792] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:27.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:64:0) after 1m0s
[18:05:27.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:64:0)
[18:05:27.914] Timer tick, asking Buildbucket for the build status
[18:05:28.004] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:28.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:65:0) after 1m0s
[18:06:28.038] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:65:0)
[18:06:28.038] Timer tick, asking Buildbucket for the build status
[18:06:28.110] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:28.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:66:0) after 1m0s
[18:07:28.119] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:66:0)
[18:07:28.119] Timer tick, asking Buildbucket for the build status
[18:07:28.200] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:28.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:67:0) after 1m0s
[18:08:28.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:67:0)
[18:08:28.231] Timer tick, asking Buildbucket for the build status
[18:08:28.479] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:28.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:68:0) after 1m0s
[18:09:28.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:68:0)
[18:09:28.519] Timer tick, asking Buildbucket for the build status
[18:09:28.847] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:28.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:69:0) after 1m0s
[18:10:28.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:69:0)
[18:10:28.854] Timer tick, asking Buildbucket for the build status
[18:10:28.909] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:28.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:70:0) after 1m0s
[18:11:28.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:70:0)
[18:11:28.930] Timer tick, asking Buildbucket for the build status
[18:11:29.319] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:29.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:71:0) after 1m0s
[18:12:29.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:71:0)
[18:12:29.342] Timer tick, asking Buildbucket for the build status
[18:12:29.501] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:29.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:72:0) after 1m0s
[18:13:29.528] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:72:0)
[18:13:29.528] Timer tick, asking Buildbucket for the build status
[18:13:30.382] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:30.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:73:0) after 1m0s
[18:14:30.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:73:0)
[18:14:30.417] Timer tick, asking Buildbucket for the build status
[18:14:30.568] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:30.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:74:0) after 1m0s
[18:15:30.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:74:0)
[18:15:30.620] Timer tick, asking Buildbucket for the build status
[18:15:30.693] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:30.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:75:0) after 1m0s
[18:16:30.721] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:75:0)
[18:16:30.721] Timer tick, asking Buildbucket for the build status
[18:16:30.835] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:30.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:76:0) after 1m0s
[18:17:31.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:76:0)
[18:17:31.074] Timer tick, asking Buildbucket for the build status
[18:17:31.363] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:31.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:77:0) after 1m0s
[18:18:31.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:77:0)
[18:18:31.385] Timer tick, asking Buildbucket for the build status
[18:18:31.554] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:31.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:78:0) after 1m0s
[18:19:31.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:78:0)
[18:19:31.683] Timer tick, asking Buildbucket for the build status
[18:19:31.797] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:31.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:79:0) after 1m0s
[18:20:31.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:79:0)
[18:20:31.810] Timer tick, asking Buildbucket for the build status
[18:20:31.927] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:31.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:80:0) after 1m0s
[18:21:31.991] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:80:0)
[18:21:31.991] Timer tick, asking Buildbucket for the build status
[18:21:32.071] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:32.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:81:0) after 1m0s
[18:22:32.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:81:0)
[18:22:32.123] Timer tick, asking Buildbucket for the build status
[18:22:32.163] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:32.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:82:0) after 1m0s
[18:23:32.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:82:0)
[18:23:32.185] Timer tick, asking Buildbucket for the build status
[18:23:32.267] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:32.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:83:0) after 1m0s
[18:24:32.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:83:0)
[18:24:32.308] Timer tick, asking Buildbucket for the build status
[18:24:32.445] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:32.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:84:0) after 1m0s
[18:25:32.435] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:84:0)
[18:25:32.435] Timer tick, asking Buildbucket for the build status
[18:25:32.566] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:32.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:85:0) after 1m0s
[18:26:32.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:85:0)
[18:26:32.592] Timer tick, asking Buildbucket for the build status
[18:26:32.776] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:32.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:86:0) after 1m0s
[18:27:32.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:86:0)
[18:27:32.803] Timer tick, asking Buildbucket for the build status
[18:27:32.873] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:32.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:87:0) after 1m0s
[18:28:32.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:87:0)
[18:28:32.859] Timer tick, asking Buildbucket for the build status
[18:28:32.988] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:32.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:88:0) after 1m0s
[18:29:33.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:88:0)
[18:29:33.091] Timer tick, asking Buildbucket for the build status
[18:29:33.204] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:33.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:89:0) after 1m0s
[18:30:33.226] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:89:0)
[18:30:33.226] Timer tick, asking Buildbucket for the build status
[18:30:33.402] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:33.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:90:0) after 1m0s
[18:31:33.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:90:0)
[18:31:33.432] Timer tick, asking Buildbucket for the build status
[18:31:33.487] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:33.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:91:0) after 1m0s
[18:32:33.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:91:0)
[18:32:33.477] Timer tick, asking Buildbucket for the build status
[18:32:33.557] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:33.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:92:0) after 1m0s
[18:33:33.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:92:0)
[18:33:33.583] Timer tick, asking Buildbucket for the build status
[18:33:33.641] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:33.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:93:0) after 1m0s
[18:34:33.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:93:0)
[18:34:33.664] Timer tick, asking Buildbucket for the build status
[18:34:33.880] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:33.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:94:0) after 1m0s
[18:35:33.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:94:0)
[18:35:33.911] Timer tick, asking Buildbucket for the build status
[18:35:34.033] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:34.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:95:0) after 1m0s
[18:36:34.059] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:95:0)
[18:36:34.059] Timer tick, asking Buildbucket for the build status
[18:36:34.433] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:34.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:96:0) after 1m0s
[18:37:34.462] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:96:0)
[18:37:34.462] Timer tick, asking Buildbucket for the build status
[18:37:35.066] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:35.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:97:0) after 1m0s
[18:38:35.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:97:0)
[18:38:35.090] Timer tick, asking Buildbucket for the build status
[18:38:35.135] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:35.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:98:0) after 1m0s
[18:39:35.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:98:0)
[18:39:35.198] Timer tick, asking Buildbucket for the build status
[18:39:35.389] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:35.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:99:0) after 1m0s
[18:40:35.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:99:0)
[18:40:35.420] Timer tick, asking Buildbucket for the build status
[18:40:35.620] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:35.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:100:0) after 1m0s
[18:41:35.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:100:0)
[18:41:35.642] Timer tick, asking Buildbucket for the build status
[18:41:35.720] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:35.720] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:101:0) after 1m0s
[18:42:35.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:101:0)
[18:42:35.743] Timer tick, asking Buildbucket for the build status
[18:42:35.974] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:35.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:102:0) after 1m0s
[18:43:36.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:102:0)
[18:43:36.045] Timer tick, asking Buildbucket for the build status
[18:43:36.606] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:36.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:103:0) after 1m0s
[18:44:36.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:103:0)
[18:44:36.608] Timer tick, asking Buildbucket for the build status
[18:44:36.691] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:36.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:104:0) after 1m0s
[18:45:36.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:104:0)
[18:45:36.717] Timer tick, asking Buildbucket for the build status
[18:45:36.776] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:36.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:105:0) after 1m0s
[18:46:36.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:105:0)
[18:46:36.798] Timer tick, asking Buildbucket for the build status
[18:46:36.877] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:36.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:106:0) after 1m0s
[18:47:36.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:106:0)
[18:47:36.869] Timer tick, asking Buildbucket for the build status
[18:47:36.984] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:36.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:107:0) after 1m0s
[18:48:37.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:107:0)
[18:48:37.013] Timer tick, asking Buildbucket for the build status
[18:48:37.178] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:37.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:108:0) after 1m0s
[18:49:37.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:108:0)
[18:49:37.209] Timer tick, asking Buildbucket for the build status
[18:49:37.333] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:37.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:109:0) after 1m0s
[18:50:37.364] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:109:0)
[18:50:37.364] Timer tick, asking Buildbucket for the build status
[18:50:37.727] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:37.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:110:0) after 1m0s
[18:51:37.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:110:0)
[18:51:37.768] Timer tick, asking Buildbucket for the build status
[18:51:37.968] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:37.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:111:0) after 1m0s
[18:52:37.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:111:0)
[18:52:37.973] Timer tick, asking Buildbucket for the build status
[18:52:38.779] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:38.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:112:0) after 1m0s
[18:57:56.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:112:0)
[18:57:56.730] Timer tick, asking Buildbucket for the build status
[18:57:56.833] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:56.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:113:0) after 1m0s
[18:58:56.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:113:0)
[18:58:56.881] Timer tick, asking Buildbucket for the build status
[18:58:56.932] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:56.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:114:0) after 1m0s
[18:59:56.931] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:114:0)
[18:59:56.931] Timer tick, asking Buildbucket for the build status
[18:59:57.003] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:57.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:115:0) after 1m0s
[19:00:57.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:115:0)
[19:00:57.031] Timer tick, asking Buildbucket for the build status
[19:00:57.295] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:57.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:116:0) after 1m0s
[19:01:57.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:116:0)
[19:01:57.317] Timer tick, asking Buildbucket for the build status
[19:01:57.408] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:57.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:117:0) after 1m0s
[19:02:57.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:117:0)
[19:02:57.485] Timer tick, asking Buildbucket for the build status
[19:02:57.860] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:57.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:118:0) after 1m0s
[19:03:57.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:118:0)
[19:03:57.886] Timer tick, asking Buildbucket for the build status
[19:03:58.404] Build 8913737824751787104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:58.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079167886720783936:119:0) after 1m0s
[19:04:13.260] Received PubSub notification, asking Buildbucket for the build status
[19:04:13.377] Build 8913737824751787104: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:04:13.377] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557601451434489",
"created_by": "project:fuchsia",
"created_ts": "1557594195563770",
"failure_reason": "BUILD_FAILURE",
"id": "8913737824751787104",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"e4c5b6d9bddd4cb311794fb69c3d9e9206828bfe\"}}",
"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-prod-1-us-central1-c-1-d1n6\", \"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\": \"e4c5b6d9bddd4cb311794fb69c3d9e9206828bfe\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-1-d1n6\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"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\": [\"4292-d8c05fa\"], \"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": "1557594228867624",
"status": "COMPLETED",
"status_changed_ts": "1557594229736421",
"tags": [
"builder:clang",
"buildset:commit/git/e4c5b6d9bddd4cb311794fb69c3d9e9206828bfe",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/e4c5b6d9bddd4cb311794fb69c3d9e9206828bfe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079167886720783936",
"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/8913737824751787104/+/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:44c0aadae3352e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1557601452716933",
"url": "https://ci.chromium.org/b/8913737824751787104",
"utcnow_ts": "1557601453369252"
}
}
[19:04:13.377] Invocation finished in 2h0m59.076731619s with status FAILED