[13:40:10.662] New invocation is queued and will start shortly
[13:40:11.742] Starting the invocation (attempt 1)
[13:40:11.776] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:40:11.776] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:40:11.776] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078093498612801328",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"6b48742e43f603b6458ed02dd4dc5d8021922a21\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078093498612801328",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/6b48742e43f603b6458ed02dd4dc5d8021922a21",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/6b48742e43f603b6458ed02dd4dc5d8021922a21",
"gitiles_ref:refs/heads/master"
]
}
[13:40:13.911] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558618811931527",
"id": "8912663436618755024",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"6b48742e43f603b6458ed02dd4dc5d8021922a21\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558618813131613",
"tags": [
"builder:clang",
"buildset:commit/git/6b48742e43f603b6458ed02dd4dc5d8021922a21",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/6b48742e43f603b6458ed02dd4dc5d8021922a21",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078093498612801328",
"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/8912663436618755024/+/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": "1558618813131742",
"url": "https://ci.chromium.org/b/8912663436618755024",
"utcnow_ts": "1558618813893451"
}
}
[13:40:13.911] Task URL: https://ci.chromium.org/b/8912663436618755024
[13:40:13.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:2:0) after 1m0s
[13:40:17.435] Received PubSub notification, asking Buildbucket for the build status
[13:40:17.517] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:13.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:2:0)
[13:41:13.911] Timer tick, asking Buildbucket for the build status
[13:41:14.858] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:14.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:4:0) after 1m0s
[13:42:16.960] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:4:0)
[13:42:16.960] Timer tick, asking Buildbucket for the build status
[13:42:17.100] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:17.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:5:0) after 1m0s
[13:43:17.422] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:5:0)
[13:43:17.422] Timer tick, asking Buildbucket for the build status
[13:43:17.580] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:17.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:6:0) after 1m0s
[13:44:17.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:6:0)
[13:44:17.669] Timer tick, asking Buildbucket for the build status
[13:44:17.981] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:17.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:7:0) after 1m0s
[13:45:18.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:7:0)
[13:45:18.174] Timer tick, asking Buildbucket for the build status
[13:45:19.072] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:19.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:8:0) after 1m0s
[13:46:19.352] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:8:0)
[13:46:19.352] Timer tick, asking Buildbucket for the build status
[13:46:20.005] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:20.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:9:0) after 1m0s
[13:47:20.054] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:9:0)
[13:47:20.054] Timer tick, asking Buildbucket for the build status
[13:47:20.133] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:20.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:10:0) after 1m0s
[13:48:20.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:10:0)
[13:48:20.174] Timer tick, asking Buildbucket for the build status
[13:48:20.267] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:20.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:11:0) after 1m0s
[13:49:20.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:11:0)
[13:49:20.299] Timer tick, asking Buildbucket for the build status
[13:49:20.424] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:20.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:12:0) after 1m0s
[13:50:20.447] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:12:0)
[13:50:20.447] Timer tick, asking Buildbucket for the build status
[13:50:20.521] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:20.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:13:0) after 1m0s
[13:51:20.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:13:0)
[13:51:20.531] Timer tick, asking Buildbucket for the build status
[13:51:20.604] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:20.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:14:0) after 1m0s
[13:52:20.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:14:0)
[13:52:20.650] Timer tick, asking Buildbucket for the build status
[13:52:20.767] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:20.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:15:0) after 1m0s
[13:53:20.817] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:15:0)
[13:53:20.817] Timer tick, asking Buildbucket for the build status
[13:53:20.864] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:20.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:16:0) after 1m0s
[13:54:20.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:16:0)
[13:54:20.888] Timer tick, asking Buildbucket for the build status
[13:54:21.153] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:21.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:17:0) after 1m0s
[13:55:21.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:17:0)
[13:55:21.163] Timer tick, asking Buildbucket for the build status
[13:55:21.534] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:21.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:18:0) after 1m0s
[13:56:21.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:18:0)
[13:56:21.551] Timer tick, asking Buildbucket for the build status
[13:56:21.627] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:21.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:19:0) after 1m0s
[13:57:21.652] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:19:0)
[13:57:21.652] Timer tick, asking Buildbucket for the build status
[13:57:21.738] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:21.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:20:0) after 1m0s
[13:58:21.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:20:0)
[13:58:21.770] Timer tick, asking Buildbucket for the build status
[13:58:21.866] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:21.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:21:0) after 1m0s
[13:59:21.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:21:0)
[13:59:21.899] Timer tick, asking Buildbucket for the build status
[13:59:22.248] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:22.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:22:0) after 1m0s
[14:00:22.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:22:0)
[14:00:22.301] Timer tick, asking Buildbucket for the build status
[14:00:22.411] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:22.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:23:0) after 1m0s
[14:01:22.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:23:0)
[14:01:22.385] Timer tick, asking Buildbucket for the build status
[14:01:22.501] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:22.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:24:0) after 1m0s
[14:02:22.663] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:24:0)
[14:02:22.663] Timer tick, asking Buildbucket for the build status
[14:02:22.748] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:22.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:25:0) after 1m0s
[14:03:22.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:25:0)
[14:03:22.768] Timer tick, asking Buildbucket for the build status
[14:03:22.991] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:22.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:26:0) after 1m0s
[14:04:23.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:26:0)
[14:04:23.061] Timer tick, asking Buildbucket for the build status
[14:04:23.245] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:23.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:27:0) after 1m0s
[14:05:23.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:27:0)
[14:05:23.314] Timer tick, asking Buildbucket for the build status
[14:05:23.704] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:23.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:28:0) after 1m0s
[14:06:23.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:28:0)
[14:06:23.730] Timer tick, asking Buildbucket for the build status
[14:06:23.837] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:23.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:29:0) after 1m0s
[14:07:23.858] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:29:0)
[14:07:23.858] Timer tick, asking Buildbucket for the build status
[14:07:24.666] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:24.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:30:0) after 1m0s
[14:08:24.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:30:0)
[14:08:24.696] Timer tick, asking Buildbucket for the build status
[14:08:24.891] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:24.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:31:0) after 1m0s
[14:09:24.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:31:0)
[14:09:24.902] Timer tick, asking Buildbucket for the build status
[14:09:25.242] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:25.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:32:0) after 1m0s
[14:10:25.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:32:0)
[14:10:25.273] Timer tick, asking Buildbucket for the build status
[14:10:25.458] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:25.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:33:0) after 1m0s
[14:11:25.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:33:0)
[14:11:25.519] Timer tick, asking Buildbucket for the build status
[14:11:25.594] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:25.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:34:0) after 1m0s
[14:12:25.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:34:0)
[14:12:25.664] Timer tick, asking Buildbucket for the build status
[14:12:25.709] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:25.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:35:0) after 1m0s
[14:13:25.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:35:0)
[14:13:25.756] Timer tick, asking Buildbucket for the build status
[14:13:25.819] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:25.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:36:0) after 1m0s
[14:14:25.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:36:0)
[14:14:25.865] Timer tick, asking Buildbucket for the build status
[14:14:25.959] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:25.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:37:0) after 1m0s
[14:15:28.977] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:37:0)
[14:15:28.977] Timer tick, asking Buildbucket for the build status
[14:15:29.181] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:29.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:38:0) after 1m0s
[14:16:29.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:38:0)
[14:16:29.201] Timer tick, asking Buildbucket for the build status
[14:16:29.488] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:29.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:39:0) after 1m0s
[14:17:29.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:39:0)
[14:17:29.471] Timer tick, asking Buildbucket for the build status
[14:17:29.945] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:29.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:40:0) after 1m0s
[14:18:29.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:40:0)
[14:18:29.959] Timer tick, asking Buildbucket for the build status
[14:18:30.061] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:30.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:41:0) after 1m0s
[14:19:30.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:41:0)
[14:19:30.089] Timer tick, asking Buildbucket for the build status
[14:19:30.255] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:30.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:42:0) after 1m0s
[14:20:30.285] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:42:0)
[14:20:30.285] Timer tick, asking Buildbucket for the build status
[14:20:30.707] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:30.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:43:0) after 1m0s
[14:21:30.744] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:43:0)
[14:21:30.744] Timer tick, asking Buildbucket for the build status
[14:21:31.176] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:31.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:44:0) after 1m0s
[14:22:31.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:44:0)
[14:22:31.210] Timer tick, asking Buildbucket for the build status
[14:22:31.285] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:31.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:45:0) after 1m0s
[14:23:31.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:45:0)
[14:23:31.393] Timer tick, asking Buildbucket for the build status
[14:23:31.461] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:31.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:46:0) after 1m0s
[14:24:31.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:46:0)
[14:24:31.576] Timer tick, asking Buildbucket for the build status
[14:24:31.690] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:31.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:47:0) after 1m0s
[14:25:31.714] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:47:0)
[14:25:31.714] Timer tick, asking Buildbucket for the build status
[14:25:32.093] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:32.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:48:0) after 1m0s
[14:26:32.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:48:0)
[14:26:32.194] Timer tick, asking Buildbucket for the build status
[14:26:32.276] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:32.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:49:0) after 1m0s
[14:27:32.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:49:0)
[14:27:32.487] Timer tick, asking Buildbucket for the build status
[14:27:32.565] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:32.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:50:0) after 1m0s
[14:28:32.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:50:0)
[14:28:32.591] Timer tick, asking Buildbucket for the build status
[14:28:32.674] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:32.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:51:0) after 1m0s
[14:29:32.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:51:0)
[14:29:32.738] Timer tick, asking Buildbucket for the build status
[14:29:32.780] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:32.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:52:0) after 1m0s
[14:30:32.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:52:0)
[14:30:32.810] Timer tick, asking Buildbucket for the build status
[14:30:32.929] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:32.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:53:0) after 1m0s
[14:31:32.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:53:0)
[14:31:32.994] Timer tick, asking Buildbucket for the build status
[14:31:33.114] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:33.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:54:0) after 1m0s
[14:32:33.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:54:0)
[14:32:33.168] Timer tick, asking Buildbucket for the build status
[14:32:33.244] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:33.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:55:0) after 1m0s
[14:33:33.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:55:0)
[14:33:33.377] Timer tick, asking Buildbucket for the build status
[14:33:33.546] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:33.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:56:0) after 1m0s
[14:34:33.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:56:0)
[14:34:33.521] Timer tick, asking Buildbucket for the build status
[14:34:33.611] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:33.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:57:0) after 1m0s
[14:35:33.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:57:0)
[14:35:33.642] Timer tick, asking Buildbucket for the build status
[14:35:33.714] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:33.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:58:0) after 1m0s
[14:36:33.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:58:0)
[14:36:33.791] Timer tick, asking Buildbucket for the build status
[14:36:33.931] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:33.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:59:0) after 1m0s
[14:37:33.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:59:0)
[14:37:33.993] Timer tick, asking Buildbucket for the build status
[14:37:34.036] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:34.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:60:0) after 1m0s
[14:38:34.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:60:0)
[14:38:34.029] Timer tick, asking Buildbucket for the build status
[14:38:34.141] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:34.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:61:0) after 1m0s
[14:39:34.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:61:0)
[14:39:34.309] Timer tick, asking Buildbucket for the build status
[14:39:34.606] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:34.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:62:0) after 1m0s
[14:40:34.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:62:0)
[14:40:34.756] Timer tick, asking Buildbucket for the build status
[14:40:34.913] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:34.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:63:0) after 1m0s
[14:41:36.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:63:0)
[14:41:36.199] Timer tick, asking Buildbucket for the build status
[14:41:36.386] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:36.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:64:0) after 1m0s
[14:42:36.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:64:0)
[14:42:36.471] Timer tick, asking Buildbucket for the build status
[14:42:36.661] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:36.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:65:0) after 1m0s
[14:43:36.724] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:65:0)
[14:43:36.724] Timer tick, asking Buildbucket for the build status
[14:43:36.784] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:36.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:66:0) after 1m0s
[14:44:36.840] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:66:0)
[14:44:36.840] Timer tick, asking Buildbucket for the build status
[14:44:37.400] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:37.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:67:0) after 1m0s
[14:45:37.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:67:0)
[14:45:37.423] Timer tick, asking Buildbucket for the build status
[14:45:37.529] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:37.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:68:0) after 1m0s
[14:46:37.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:68:0)
[14:46:37.552] Timer tick, asking Buildbucket for the build status
[14:46:38.547] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:38.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:69:0) after 1m0s
[14:47:38.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:69:0)
[14:47:38.567] Timer tick, asking Buildbucket for the build status
[14:47:38.801] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:38.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:70:0) after 1m0s
[14:48:38.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:70:0)
[14:48:38.904] Timer tick, asking Buildbucket for the build status
[14:48:39.453] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:39.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:71:0) after 1m0s
[14:49:39.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:71:0)
[14:49:39.491] Timer tick, asking Buildbucket for the build status
[14:49:39.587] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:39.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:72:0) after 1m0s
[14:50:39.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:72:0)
[14:50:39.695] Timer tick, asking Buildbucket for the build status
[14:50:39.827] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:39.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:73:0) after 1m0s
[14:51:39.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:73:0)
[14:51:39.880] Timer tick, asking Buildbucket for the build status
[14:51:40.118] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:40.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:74:0) after 1m0s
[14:52:40.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:74:0)
[14:52:40.224] Timer tick, asking Buildbucket for the build status
[14:52:40.282] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:40.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:75:0) after 1m0s
[14:53:40.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:75:0)
[14:53:40.338] Timer tick, asking Buildbucket for the build status
[14:53:40.478] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:40.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:76:0) after 1m0s
[14:54:40.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:76:0)
[14:54:40.516] Timer tick, asking Buildbucket for the build status
[14:54:40.567] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:40.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:77:0) after 1m0s
[14:55:40.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:77:0)
[14:55:40.616] Timer tick, asking Buildbucket for the build status
[14:55:40.682] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:40.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:78:0) after 1m0s
[14:56:40.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:78:0)
[14:56:40.742] Timer tick, asking Buildbucket for the build status
[14:56:40.794] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:40.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:79:0) after 1m0s
[14:57:40.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:79:0)
[14:57:40.829] Timer tick, asking Buildbucket for the build status
[14:57:40.894] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:40.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:80:0) after 1m0s
[14:58:41.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:80:0)
[14:58:41.092] Timer tick, asking Buildbucket for the build status
[14:58:41.207] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:41.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:81:0) after 1m0s
[14:59:41.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:81:0)
[14:59:41.383] Timer tick, asking Buildbucket for the build status
[14:59:41.681] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:41.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:82:0) after 1m0s
[15:00:41.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:82:0)
[15:00:41.728] Timer tick, asking Buildbucket for the build status
[15:00:41.883] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:41.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:83:0) after 1m0s
[15:01:41.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:83:0)
[15:01:41.956] Timer tick, asking Buildbucket for the build status
[15:01:42.103] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:42.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:84:0) after 1m0s
[15:02:42.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:84:0)
[15:02:42.210] Timer tick, asking Buildbucket for the build status
[15:02:42.323] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:42.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:85:0) after 1m0s
[15:03:42.450] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:85:0)
[15:03:42.450] Timer tick, asking Buildbucket for the build status
[15:03:42.616] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:42.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:86:0) after 1m0s
[15:04:42.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:86:0)
[15:04:42.727] Timer tick, asking Buildbucket for the build status
[15:04:42.888] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:42.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:87:0) after 1m0s
[15:05:42.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:87:0)
[15:05:42.955] Timer tick, asking Buildbucket for the build status
[15:05:44.304] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:44.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:88:0) after 1m0s
[15:06:44.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:88:0)
[15:06:44.359] Timer tick, asking Buildbucket for the build status
[15:06:45.116] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:45.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:89:0) after 1m0s
[15:07:45.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:89:0)
[15:07:45.092] Timer tick, asking Buildbucket for the build status
[15:07:45.558] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:45.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:90:0) after 1m0s
[15:08:45.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:90:0)
[15:08:45.619] Timer tick, asking Buildbucket for the build status
[15:08:45.893] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:45.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:91:0) after 1m0s
[15:09:46.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:91:0)
[15:09:46.015] Timer tick, asking Buildbucket for the build status
[15:09:46.140] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:46.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:92:0) after 1m0s
[15:10:46.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:92:0)
[15:10:46.199] Timer tick, asking Buildbucket for the build status
[15:10:47.115] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:47.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:93:0) after 1m0s
[15:11:47.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:93:0)
[15:11:47.185] Timer tick, asking Buildbucket for the build status
[15:11:47.639] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:47.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:94:0) after 1m0s
[15:12:47.702] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:94:0)
[15:12:47.702] Timer tick, asking Buildbucket for the build status
[15:12:47.758] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:47.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:95:0) after 1m0s
[15:13:47.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:95:0)
[15:13:47.810] Timer tick, asking Buildbucket for the build status
[15:13:47.863] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:47.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:96:0) after 1m0s
[15:14:47.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:96:0)
[15:14:47.917] Timer tick, asking Buildbucket for the build status
[15:14:48.008] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:48.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:97:0) after 1m0s
[15:15:48.023] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:97:0)
[15:15:48.023] Timer tick, asking Buildbucket for the build status
[15:15:48.271] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:48.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:98:0) after 1m0s
[15:16:48.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:98:0)
[15:16:48.312] Timer tick, asking Buildbucket for the build status
[15:16:48.423] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:48.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:99:0) after 1m0s
[15:17:48.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:99:0)
[15:17:48.517] Timer tick, asking Buildbucket for the build status
[15:17:48.746] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:48.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:100:0) after 1m0s
[15:18:48.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:100:0)
[15:18:48.773] Timer tick, asking Buildbucket for the build status
[15:18:49.006] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:49.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:101:0) after 1m0s
[15:19:49.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:101:0)
[15:19:49.044] Timer tick, asking Buildbucket for the build status
[15:19:49.126] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:49.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:102:0) after 1m0s
[15:20:49.229] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:102:0)
[15:20:49.229] Timer tick, asking Buildbucket for the build status
[15:20:49.308] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:49.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:103:0) after 1m0s
[15:21:49.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:103:0)
[15:21:49.346] Timer tick, asking Buildbucket for the build status
[15:21:49.396] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:49.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:104:0) after 1m0s
[15:22:49.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:104:0)
[15:22:49.446] Timer tick, asking Buildbucket for the build status
[15:22:49.506] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:49.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:105:0) after 1m0s
[15:23:49.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:105:0)
[15:23:49.612] Timer tick, asking Buildbucket for the build status
[15:23:49.739] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:49.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:106:0) after 1m0s
[15:24:49.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:106:0)
[15:24:49.767] Timer tick, asking Buildbucket for the build status
[15:24:49.962] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:49.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:107:0) after 1m0s
[15:25:50.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:107:0)
[15:25:50.017] Timer tick, asking Buildbucket for the build status
[15:25:50.490] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:50.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:108:0) after 1m0s
[15:26:50.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:108:0)
[15:26:50.522] Timer tick, asking Buildbucket for the build status
[15:26:50.563] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:50.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:109:0) after 1m0s
[15:27:50.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:109:0)
[15:27:50.596] Timer tick, asking Buildbucket for the build status
[15:27:50.833] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:50.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:110:0) after 1m0s
[15:28:51.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:110:0)
[15:28:51.103] Timer tick, asking Buildbucket for the build status
[15:28:51.517] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:51.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:111:0) after 1m0s
[15:29:51.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:111:0)
[15:29:51.590] Timer tick, asking Buildbucket for the build status
[15:29:51.752] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:51.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:112:0) after 1m0s
[15:30:51.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:112:0)
[15:30:51.910] Timer tick, asking Buildbucket for the build status
[15:30:51.983] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:51.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:113:0) after 1m0s
[15:31:52.042] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:113:0)
[15:31:52.042] Timer tick, asking Buildbucket for the build status
[15:32:07.286] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:07.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:114:0) after 1m0s
[15:33:07.339] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:114:0)
[15:33:07.339] Timer tick, asking Buildbucket for the build status
[15:33:07.400] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:07.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:115:0) after 1m0s
[15:34:07.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:115:0)
[15:34:07.421] Timer tick, asking Buildbucket for the build status
[15:34:07.720] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:07.720] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:116:0) after 1m0s
[15:35:07.721] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:116:0)
[15:35:07.721] Timer tick, asking Buildbucket for the build status
[15:35:07.857] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:07.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:117:0) after 1m0s
[15:36:07.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:117:0)
[15:36:07.881] Timer tick, asking Buildbucket for the build status
[15:36:08.123] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:08.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:118:0) after 1m0s
[15:37:08.176] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:118:0)
[15:37:08.176] Timer tick, asking Buildbucket for the build status
[15:37:08.223] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:08.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:119:0) after 1m0s
[15:38:08.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:119:0)
[15:38:08.202] Timer tick, asking Buildbucket for the build status
[15:38:08.266] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:08.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:120:0) after 1m0s
[15:39:08.339] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:120:0)
[15:39:08.339] Timer tick, asking Buildbucket for the build status
[15:39:08.607] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:08.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:121:0) after 1m0s
[15:40:08.645] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:121:0)
[15:40:08.645] Timer tick, asking Buildbucket for the build status
[15:40:09.618] Build 8912663436618755024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:09.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078093498612801328:122:0) after 1m0s
[15:40:40.840] Received PubSub notification, asking Buildbucket for the build status
[15:40:41.010] Build 8912663436618755024: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:40:41.010] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558626039511532",
"created_by": "project:fuchsia",
"created_ts": "1558618811931527",
"failure_reason": "BUILD_FAILURE",
"id": "8912663436618755024",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"6b48742e43f603b6458ed02dd4dc5d8021922a21\"}}",
"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-b-0-7ymi\", \"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\": \"6b48742e43f603b6458ed02dd4dc5d8021922a21\", \"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-b-0-7ymi\"], \"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\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1558618815486721",
"status": "COMPLETED",
"status_changed_ts": "1558618816689090",
"tags": [
"builder:clang",
"buildset:commit/git/6b48742e43f603b6458ed02dd4dc5d8021922a21",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/6b48742e43f603b6458ed02dd4dc5d8021922a21",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078093498612801328",
"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/8912663436618755024/+/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:44fdbd3ffecc5610",
"user_agent:luci-scheduler"
],
"updated_ts": "1558626039608429",
"url": "https://ci.chromium.org/b/8912663436618755024",
"utcnow_ts": "1558626041000552"
}
}
[15:40:41.010] Invocation finished in 2h0m30.364806297s with status FAILED