[15:40:42.167] New invocation is queued and will start shortly
[15:40:43.246] Starting the invocation (attempt 1)
[15:40:43.281] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:40:43.281] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:40:43.281] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078085915829617168",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"762d008a7c35c84468f713b93748190a52f0dfe5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078085915829617168",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/762d008a7c35c84468f713b93748190a52f0dfe5",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/762d008a7c35c84468f713b93748190a52f0dfe5",
"gitiles_ref:refs/heads/master"
]
}
[15:40:44.797] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558626043449812",
"id": "8912655853822195888",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"762d008a7c35c84468f713b93748190a52f0dfe5\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558626044507918",
"tags": [
"builder:clang",
"buildset:commit/git/762d008a7c35c84468f713b93748190a52f0dfe5",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/762d008a7c35c84468f713b93748190a52f0dfe5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078085915829617168",
"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/8912655853822195888/+/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": "1558626044508067",
"url": "https://ci.chromium.org/b/8912655853822195888",
"utcnow_ts": "1558626044781961"
}
}
[15:40:44.797] Task URL: https://ci.chromium.org/b/8912655853822195888
[15:40:44.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:2:0) after 1m0s
[15:41:18.545] Received PubSub notification, asking Buildbucket for the build status
[15:41:19.158] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:44.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:2:0)
[15:41:44.878] Timer tick, asking Buildbucket for the build status
[15:41:45.286] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:45.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:4:0) after 1m0s
[15:42:45.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:4:0)
[15:42:45.385] Timer tick, asking Buildbucket for the build status
[15:42:45.981] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:45.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:5:0) after 1m0s
[15:43:46.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:5:0)
[15:43:46.028] Timer tick, asking Buildbucket for the build status
[15:43:46.196] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:46.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:6:0) after 1m0s
[15:44:46.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:6:0)
[15:44:46.222] Timer tick, asking Buildbucket for the build status
[15:44:46.330] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:46.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:7:0) after 1m0s
[15:45:46.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:7:0)
[15:45:46.361] Timer tick, asking Buildbucket for the build status
[15:45:46.856] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:46.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:8:0) after 1m0s
[15:46:46.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:8:0)
[15:46:46.894] Timer tick, asking Buildbucket for the build status
[15:46:47.526] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:47.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:9:0) after 1m0s
[15:47:47.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:9:0)
[15:47:47.551] Timer tick, asking Buildbucket for the build status
[15:47:47.594] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:47.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:10:0) after 1m0s
[15:48:47.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:10:0)
[15:48:47.622] Timer tick, asking Buildbucket for the build status
[15:48:47.681] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:47.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:11:0) after 1m0s
[15:49:47.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:11:0)
[15:49:47.701] Timer tick, asking Buildbucket for the build status
[15:49:47.891] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:47.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:12:0) after 1m0s
[15:50:47.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:12:0)
[15:50:47.929] Timer tick, asking Buildbucket for the build status
[15:50:48.204] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:48.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:13:0) after 1m0s
[15:51:48.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:13:0)
[15:51:48.274] Timer tick, asking Buildbucket for the build status
[15:51:48.325] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:48.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:14:0) after 1m0s
[15:52:48.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:14:0)
[15:52:48.362] Timer tick, asking Buildbucket for the build status
[15:52:48.520] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:48.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:15:0) after 1m0s
[15:53:48.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:15:0)
[15:53:48.599] Timer tick, asking Buildbucket for the build status
[15:53:49.060] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:49.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:16:0) after 1m0s
[15:54:49.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:16:0)
[15:54:49.163] Timer tick, asking Buildbucket for the build status
[15:54:54.829] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:54.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:17:0) after 1m0s
[15:55:54.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:17:0)
[15:55:54.969] Timer tick, asking Buildbucket for the build status
[15:55:55.316] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:55.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:18:0) after 1m0s
[15:56:55.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:18:0)
[15:56:55.390] Timer tick, asking Buildbucket for the build status
[15:56:55.441] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:55.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:19:0) after 1m0s
[15:57:55.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:19:0)
[15:57:55.590] Timer tick, asking Buildbucket for the build status
[15:57:55.678] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:55.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:20:0) after 1m0s
[15:58:55.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:20:0)
[15:58:55.730] Timer tick, asking Buildbucket for the build status
[15:58:55.920] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:55.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:21:0) after 1m0s
[15:59:55.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:21:0)
[15:59:55.978] Timer tick, asking Buildbucket for the build status
[15:59:56.050] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:56.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:22:0) after 1m0s
[16:00:56.158] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:22:0)
[16:00:56.158] Timer tick, asking Buildbucket for the build status
[16:00:56.698] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:56.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:23:0) after 1m0s
[16:01:56.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:23:0)
[16:01:56.769] Timer tick, asking Buildbucket for the build status
[16:01:57.449] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:57.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:24:0) after 1m0s
[16:02:57.474] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:24:0)
[16:02:57.474] Timer tick, asking Buildbucket for the build status
[16:02:57.774] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:57.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:25:0) after 1m0s
[16:03:57.834] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:25:0)
[16:03:57.834] Timer tick, asking Buildbucket for the build status
[16:03:58.539] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:58.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:26:0) after 1m0s
[16:04:58.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:26:0)
[16:04:58.522] Timer tick, asking Buildbucket for the build status
[16:04:58.850] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:58.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:27:0) after 1m0s
[16:05:58.872] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:27:0)
[16:05:58.872] Timer tick, asking Buildbucket for the build status
[16:05:58.953] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:58.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:28:0) after 1m0s
[16:06:58.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:28:0)
[16:06:58.928] Timer tick, asking Buildbucket for the build status
[16:06:59.026] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:59.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:29:0) after 1m0s
[16:07:59.046] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:29:0)
[16:07:59.046] Timer tick, asking Buildbucket for the build status
[16:07:59.131] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:59.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:30:0) after 1m0s
[16:08:59.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:30:0)
[16:08:59.164] Timer tick, asking Buildbucket for the build status
[16:08:59.241] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:59.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:31:0) after 1m0s
[16:09:59.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:31:0)
[16:09:59.283] Timer tick, asking Buildbucket for the build status
[16:09:59.667] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:59.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:32:0) after 1m0s
[16:10:59.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:32:0)
[16:10:59.689] Timer tick, asking Buildbucket for the build status
[16:10:59.762] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:59.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:33:0) after 1m0s
[16:11:59.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:33:0)
[16:11:59.818] Timer tick, asking Buildbucket for the build status
[16:12:00.445] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:00.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:34:0) after 1m0s
[16:13:00.505] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:34:0)
[16:13:00.505] Timer tick, asking Buildbucket for the build status
[16:13:00.972] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:00.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:35:0) after 1m0s
[16:14:01.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:35:0)
[16:14:01.017] Timer tick, asking Buildbucket for the build status
[16:14:01.103] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:01.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:36:0) after 1m0s
[16:15:01.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:36:0)
[16:15:01.171] Timer tick, asking Buildbucket for the build status
[16:15:01.225] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:01.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:37:0) after 1m0s
[16:16:01.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:37:0)
[16:16:01.305] Timer tick, asking Buildbucket for the build status
[16:16:01.353] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:01.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:38:0) after 1m0s
[16:17:01.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:38:0)
[16:17:01.429] Timer tick, asking Buildbucket for the build status
[16:17:02.024] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:02.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:39:0) after 1m0s
[16:18:02.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:39:0)
[16:18:02.057] Timer tick, asking Buildbucket for the build status
[16:18:02.337] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:02.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:40:0) after 1m0s
[16:19:02.341] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:40:0)
[16:19:02.341] Timer tick, asking Buildbucket for the build status
[16:19:02.488] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:02.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:41:0) after 1m0s
[16:20:02.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:41:0)
[16:20:02.492] Timer tick, asking Buildbucket for the build status
[16:20:02.915] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:02.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:42:0) after 1m0s
[16:21:02.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:42:0)
[16:21:02.898] Timer tick, asking Buildbucket for the build status
[16:21:03.539] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:03.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:43:0) after 1m0s
[16:22:03.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:43:0)
[16:22:03.602] Timer tick, asking Buildbucket for the build status
[16:22:04.215] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:04.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:44:0) after 1m0s
[16:23:04.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:44:0)
[16:23:04.373] Timer tick, asking Buildbucket for the build status
[16:23:04.643] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:04.643] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:45:0) after 1m0s
[16:24:04.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:45:0)
[16:24:04.646] Timer tick, asking Buildbucket for the build status
[16:24:04.782] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:04.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:46:0) after 1m0s
[16:25:04.835] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:46:0)
[16:25:04.835] Timer tick, asking Buildbucket for the build status
[16:25:04.894] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:04.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:47:0) after 1m0s
[16:26:04.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:47:0)
[16:26:04.921] Timer tick, asking Buildbucket for the build status
[16:26:05.312] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:05.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:48:0) after 1m0s
[16:27:05.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:48:0)
[16:27:05.411] Timer tick, asking Buildbucket for the build status
[16:27:05.546] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:05.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:49:0) after 1m0s
[16:28:05.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:49:0)
[16:28:05.579] Timer tick, asking Buildbucket for the build status
[16:28:05.682] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:05.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:50:0) after 1m0s
[16:29:05.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:50:0)
[16:29:05.676] Timer tick, asking Buildbucket for the build status
[16:29:05.778] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:05.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:51:0) after 1m0s
[16:30:05.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:51:0)
[16:30:05.799] Timer tick, asking Buildbucket for the build status
[16:30:06.614] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:06.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:52:0) after 1m0s
[16:31:06.714] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:52:0)
[16:31:06.714] Timer tick, asking Buildbucket for the build status
[16:31:06.784] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:06.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:53:0) after 1m0s
[16:32:07.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:53:0)
[16:32:07.009] Timer tick, asking Buildbucket for the build status
[16:32:07.976] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:07.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:54:0) after 1m0s
[16:33:07.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:54:0)
[16:33:07.998] Timer tick, asking Buildbucket for the build status
[16:33:08.101] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:08.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:55:0) after 1m0s
[16:34:08.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:55:0)
[16:34:08.148] Timer tick, asking Buildbucket for the build status
[16:34:08.214] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:08.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:56:0) after 1m0s
[16:35:08.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:56:0)
[16:35:08.289] Timer tick, asking Buildbucket for the build status
[16:35:08.346] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:08.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:57:0) after 1m0s
[16:36:08.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:57:0)
[16:36:08.407] Timer tick, asking Buildbucket for the build status
[16:36:08.549] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:08.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:58:0) after 1m0s
[16:37:08.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:58:0)
[16:37:08.603] Timer tick, asking Buildbucket for the build status
[16:37:08.705] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:08.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:59:0) after 1m0s
[16:38:08.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:59:0)
[16:38:08.706] Timer tick, asking Buildbucket for the build status
[16:38:08.853] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:08.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:60:0) after 1m0s
[16:39:08.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:60:0)
[16:39:08.906] Timer tick, asking Buildbucket for the build status
[16:39:09.766] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:09.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:61:0) after 1m0s
[16:40:09.816] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:61:0)
[16:40:09.816] Timer tick, asking Buildbucket for the build status
[16:40:10.117] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:10.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:62:0) after 1m0s
[16:41:10.255] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:62:0)
[16:41:10.255] Timer tick, asking Buildbucket for the build status
[16:41:10.436] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:10.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:63:0) after 1m0s
[16:42:10.409] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:63:0)
[16:42:10.409] Timer tick, asking Buildbucket for the build status
[16:42:10.757] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:10.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:64:0) after 1m0s
[16:43:10.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:64:0)
[16:43:10.794] Timer tick, asking Buildbucket for the build status
[16:43:11.213] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:11.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:65:0) after 1m0s
[16:44:11.459] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:65:0)
[16:44:11.459] Timer tick, asking Buildbucket for the build status
[16:44:11.617] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:11.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:66:0) after 1m0s
[16:45:11.758] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:66:0)
[16:45:11.758] Timer tick, asking Buildbucket for the build status
[16:45:11.896] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:11.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:67:0) after 1m0s
[16:46:11.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:67:0)
[16:46:11.985] Timer tick, asking Buildbucket for the build status
[16:46:12.085] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:12.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:68:0) after 1m0s
[16:47:12.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:68:0)
[16:47:12.159] Timer tick, asking Buildbucket for the build status
[16:47:12.267] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:12.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:69:0) after 1m0s
[16:48:12.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:69:0)
[16:48:12.289] Timer tick, asking Buildbucket for the build status
[16:48:12.338] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:12.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:70:0) after 1m0s
[16:49:12.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:70:0)
[16:49:12.359] Timer tick, asking Buildbucket for the build status
[16:49:12.428] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:12.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:71:0) after 1m0s
[16:50:12.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:71:0)
[16:50:12.469] Timer tick, asking Buildbucket for the build status
[16:50:12.531] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:12.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:72:0) after 1m0s
[16:51:12.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:72:0)
[16:51:12.569] Timer tick, asking Buildbucket for the build status
[16:51:12.739] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:12.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:73:0) after 1m0s
[16:52:12.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:73:0)
[16:52:12.764] Timer tick, asking Buildbucket for the build status
[16:52:12.846] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:12.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:74:0) after 1m0s
[16:53:13.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:74:0)
[16:53:13.218] Timer tick, asking Buildbucket for the build status
[16:53:13.267] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:13.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:75:0) after 1m0s
[16:54:13.547] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:75:0)
[16:54:13.547] Timer tick, asking Buildbucket for the build status
[16:54:13.664] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:13.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:76:0) after 1m0s
[16:55:13.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:76:0)
[16:55:13.812] Timer tick, asking Buildbucket for the build status
[16:55:14.242] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:14.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:77:0) after 1m0s
[16:56:14.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:77:0)
[16:56:14.371] Timer tick, asking Buildbucket for the build status
[16:56:14.422] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:14.422] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:78:0) after 1m0s
[16:57:14.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:78:0)
[16:57:14.599] Timer tick, asking Buildbucket for the build status
[16:57:14.700] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:14.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:79:0) after 1m0s
[16:58:14.758] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:79:0)
[16:58:14.758] Timer tick, asking Buildbucket for the build status
[16:58:15.088] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:15.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:80:0) after 1m0s
[16:59:15.150] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:80:0)
[16:59:15.150] Timer tick, asking Buildbucket for the build status
[16:59:15.646] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:15.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:81:0) after 1m0s
[17:00:15.629] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:81:0)
[17:00:15.629] Timer tick, asking Buildbucket for the build status
[17:00:15.817] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:15.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:82:0) after 1m0s
[17:01:15.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:82:0)
[17:01:15.907] Timer tick, asking Buildbucket for the build status
[17:01:15.960] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:15.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:83:0) after 1m0s
[17:02:15.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:83:0)
[17:02:15.969] Timer tick, asking Buildbucket for the build status
[17:02:16.168] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:16.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:84:0) after 1m0s
[17:03:16.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:84:0)
[17:03:16.328] Timer tick, asking Buildbucket for the build status
[17:03:16.379] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:16.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:85:0) after 1m0s
[17:04:16.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:85:0)
[17:04:16.380] Timer tick, asking Buildbucket for the build status
[17:04:16.454] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:16.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:86:0) after 1m0s
[17:05:16.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:86:0)
[17:05:16.580] Timer tick, asking Buildbucket for the build status
[17:05:16.658] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:16.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:87:0) after 1m0s
[17:06:19.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:87:0)
[17:06:19.371] Timer tick, asking Buildbucket for the build status
[17:06:19.968] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:19.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:88:0) after 1m0s
[17:07:19.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:88:0)
[17:07:19.985] Timer tick, asking Buildbucket for the build status
[17:07:20.134] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:20.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:89:0) after 1m0s
[17:08:20.239] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:89:0)
[17:08:20.239] Timer tick, asking Buildbucket for the build status
[17:08:20.384] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:20.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:90:0) after 1m0s
[17:09:20.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:90:0)
[17:09:20.443] Timer tick, asking Buildbucket for the build status
[17:09:20.601] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:20.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:91:0) after 1m0s
[17:10:20.638] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:91:0)
[17:10:20.638] Timer tick, asking Buildbucket for the build status
[17:10:20.701] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:20.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:92:0) after 1m0s
[17:11:20.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:92:0)
[17:11:20.786] Timer tick, asking Buildbucket for the build status
[17:11:20.823] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:20.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:93:0) after 1m0s
[17:12:20.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:93:0)
[17:12:20.876] Timer tick, asking Buildbucket for the build status
[17:12:20.948] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:20.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:94:0) after 1m0s
[17:13:20.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:94:0)
[17:13:20.979] Timer tick, asking Buildbucket for the build status
[17:13:21.037] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:21.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:95:0) after 1m0s
[17:14:21.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:95:0)
[17:14:21.069] Timer tick, asking Buildbucket for the build status
[17:14:21.169] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:21.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:96:0) after 1m0s
[17:15:21.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:96:0)
[17:15:21.222] Timer tick, asking Buildbucket for the build status
[17:15:21.465] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:21.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:97:0) after 1m0s
[17:16:21.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:97:0)
[17:16:21.488] Timer tick, asking Buildbucket for the build status
[17:16:21.916] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:21.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:98:0) after 1m0s
[17:17:21.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:98:0)
[17:17:21.956] Timer tick, asking Buildbucket for the build status
[17:17:22.013] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:22.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:99:0) after 1m0s
[17:18:22.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:99:0)
[17:18:22.044] Timer tick, asking Buildbucket for the build status
[17:18:22.167] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:22.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:100:0) after 1m0s
[17:19:22.154] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:100:0)
[17:19:22.154] Timer tick, asking Buildbucket for the build status
[17:19:22.250] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:22.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:101:0) after 1m0s
[17:20:22.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:101:0)
[17:20:22.274] Timer tick, asking Buildbucket for the build status
[17:20:23.376] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:23.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:102:0) after 1m0s
[17:21:23.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:102:0)
[17:21:23.388] Timer tick, asking Buildbucket for the build status
[17:21:23.438] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:23.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:103:0) after 1m0s
[17:22:23.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:103:0)
[17:22:23.480] Timer tick, asking Buildbucket for the build status
[17:22:23.935] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:23.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:104:0) after 1m0s
[17:23:24.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:104:0)
[17:23:24.001] Timer tick, asking Buildbucket for the build status
[17:23:24.062] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:24.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:105:0) after 1m0s
[17:24:24.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:105:0)
[17:24:24.172] Timer tick, asking Buildbucket for the build status
[17:24:24.380] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:24.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:106:0) after 1m0s
[17:25:24.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:106:0)
[17:25:24.423] Timer tick, asking Buildbucket for the build status
[17:25:24.482] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:24.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:107:0) after 1m0s
[17:26:24.613] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:107:0)
[17:26:24.613] Timer tick, asking Buildbucket for the build status
[17:26:24.898] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:24.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:108:0) after 1m0s
[17:27:24.960] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:108:0)
[17:27:24.960] Timer tick, asking Buildbucket for the build status
[17:27:25.559] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:25.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:109:0) after 1m0s
[17:28:25.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:109:0)
[17:28:25.601] Timer tick, asking Buildbucket for the build status
[17:28:25.689] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:25.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:110:0) after 1m0s
[17:29:25.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:110:0)
[17:29:25.786] Timer tick, asking Buildbucket for the build status
[17:29:26.020] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:26.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:111:0) after 1m0s
[17:30:26.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:111:0)
[17:30:26.061] Timer tick, asking Buildbucket for the build status
[17:30:26.135] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:26.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:112:0) after 1m0s
[17:31:26.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:112:0)
[17:31:26.174] Timer tick, asking Buildbucket for the build status
[17:31:26.449] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:26.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:113:0) after 1m0s
[17:32:26.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:113:0)
[17:32:26.478] Timer tick, asking Buildbucket for the build status
[17:32:26.605] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:26.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:114:0) after 1m0s
[17:33:26.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:114:0)
[17:33:26.669] Timer tick, asking Buildbucket for the build status
[17:33:26.812] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:26.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:115:0) after 1m0s
[17:34:26.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:115:0)
[17:34:26.882] Timer tick, asking Buildbucket for the build status
[17:34:26.973] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:26.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:116:0) after 1m0s
[17:35:27.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:116:0)
[17:35:27.330] Timer tick, asking Buildbucket for the build status
[17:35:27.377] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:27.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:117:0) after 1m0s
[17:36:27.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:117:0)
[17:36:27.537] Timer tick, asking Buildbucket for the build status
[17:36:27.600] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:27.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:118:0) after 1m0s
[17:37:27.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:118:0)
[17:37:27.766] Timer tick, asking Buildbucket for the build status
[17:37:27.902] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:27.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:119:0) after 1m0s
[17:38:27.982] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:119:0)
[17:38:27.982] Timer tick, asking Buildbucket for the build status
[17:38:28.063] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:28.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:120:0) after 1m0s
[17:39:28.288] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:120:0)
[17:39:28.288] Timer tick, asking Buildbucket for the build status
[17:39:28.365] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:28.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:121:0) after 1m0s
[17:40:28.680] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:121:0)
[17:40:28.680] Timer tick, asking Buildbucket for the build status
[17:40:29.034] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:29.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:122:0) after 1m0s
[17:41:29.143] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:122:0)
[17:41:29.143] Timer tick, asking Buildbucket for the build status
[17:41:29.628] Build 8912655853822195888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:29.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078085915829617168:123:0) after 1m0s
[17:41:45.258] Received PubSub notification, asking Buildbucket for the build status
[17:41:45.304] Build 8912655853822195888: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:41:45.304] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558633304818142",
"created_by": "project:fuchsia",
"created_ts": "1558626043449812",
"failure_reason": "BUILD_FAILURE",
"id": "8912655853822195888",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"762d008a7c35c84468f713b93748190a52f0dfe5\"}}",
"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\": \"762d008a7c35c84468f713b93748190a52f0dfe5\", \"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": "1558626075847412",
"status": "COMPLETED",
"status_changed_ts": "1558626077806530",
"tags": [
"builder:clang",
"buildset:commit/git/762d008a7c35c84468f713b93748190a52f0dfe5",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/762d008a7c35c84468f713b93748190a52f0dfe5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078085915829617168",
"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/8912655853822195888/+/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:44fe2b9cdcc88e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558633304950951",
"url": "https://ci.chromium.org/b/8912655853822195888",
"utcnow_ts": "1558633305296956"
}
}
[17:41:45.304] Invocation finished in 2h1m3.153073802s with status FAILED