[02:55:56.108] New invocation is queued and will start shortly
[02:55:57.191] Starting the invocation (attempt 1)
[02:55:57.226] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:55:57.226] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:55:57.226] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083116864003167840",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"2a3f42c90d4ba2860e039e4de92a75c75cfdc351\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083116864003167840",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/2a3f42c90d4ba2860e039e4de92a75c75cfdc351",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2a3f42c90d4ba2860e039e4de92a75c75cfdc351",
"gitiles_ref:refs/heads/master"
]
}
[02:55:58.347] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553828157375379",
"id": "8917686802010523760",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2a3f42c90d4ba2860e039e4de92a75c75cfdc351\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553828158133341",
"tags": [
"builder:clang",
"buildset:commit/git/2a3f42c90d4ba2860e039e4de92a75c75cfdc351",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2a3f42c90d4ba2860e039e4de92a75c75cfdc351",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083116864003167840",
"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/8917686802010523760/+/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": "1553828158133455",
"url": "https://ci.chromium.org/b/8917686802010523760",
"utcnow_ts": "1553828158325064"
}
}
[02:55:58.347] Task URL: https://ci.chromium.org/b/8917686802010523760
[02:55:58.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:2:0) after 1m0s
[02:56:14.547] Received PubSub notification, asking Buildbucket for the build status
[02:56:14.768] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:58.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:2:0)
[02:56:58.363] Timer tick, asking Buildbucket for the build status
[02:56:58.587] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:58.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:4:0) after 1m0s
[02:57:58.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:4:0)
[02:57:58.600] Timer tick, asking Buildbucket for the build status
[02:57:58.787] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:58.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:5:0) after 1m0s
[02:58:58.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:5:0)
[02:58:58.805] Timer tick, asking Buildbucket for the build status
[02:58:58.880] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:58.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:6:0) after 1m0s
[02:59:58.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:6:0)
[02:59:58.897] Timer tick, asking Buildbucket for the build status
[02:59:58.937] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:58.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:7:0) after 1m0s
[03:00:58.958] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:7:0)
[03:00:58.958] Timer tick, asking Buildbucket for the build status
[03:00:59.052] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:59.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:8:0) after 1m0s
[03:01:59.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:8:0)
[03:01:59.036] Timer tick, asking Buildbucket for the build status
[03:01:59.208] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:59.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:9:0) after 1m0s
[03:02:59.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:9:0)
[03:02:59.193] Timer tick, asking Buildbucket for the build status
[03:02:59.260] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:59.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:10:0) after 1m0s
[03:03:59.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:10:0)
[03:03:59.274] Timer tick, asking Buildbucket for the build status
[03:03:59.316] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:59.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:11:0) after 1m0s
[03:04:59.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:11:0)
[03:04:59.338] Timer tick, asking Buildbucket for the build status
[03:04:59.419] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:59.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:12:0) after 1m0s
[03:05:59.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:12:0)
[03:05:59.436] Timer tick, asking Buildbucket for the build status
[03:05:59.492] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:59.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:13:0) after 1m0s
[03:06:59.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:13:0)
[03:06:59.486] Timer tick, asking Buildbucket for the build status
[03:06:59.557] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:59.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:14:0) after 1m0s
[03:07:59.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:14:0)
[03:07:59.577] Timer tick, asking Buildbucket for the build status
[03:07:59.628] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:59.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:15:0) after 1m0s
[03:08:59.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:15:0)
[03:08:59.644] Timer tick, asking Buildbucket for the build status
[03:08:59.698] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:59.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:16:0) after 1m0s
[03:09:59.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:16:0)
[03:09:59.730] Timer tick, asking Buildbucket for the build status
[03:09:59.779] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:59.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:17:0) after 1m0s
[03:10:59.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:17:0)
[03:10:59.850] Timer tick, asking Buildbucket for the build status
[03:11:00.054] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:00.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:18:0) after 1m0s
[03:12:00.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:18:0)
[03:12:00.079] Timer tick, asking Buildbucket for the build status
[03:12:00.146] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:00.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:19:0) after 1m0s
[03:13:00.170] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:19:0)
[03:13:00.170] Timer tick, asking Buildbucket for the build status
[03:13:00.354] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:00.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:20:0) after 1m0s
[03:14:00.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:20:0)
[03:14:00.378] Timer tick, asking Buildbucket for the build status
[03:14:00.552] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:00.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:21:0) after 1m0s
[03:15:00.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:21:0)
[03:15:00.571] Timer tick, asking Buildbucket for the build status
[03:15:00.980] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:00.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:22:0) after 1m0s
[03:16:00.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:22:0)
[03:16:00.987] Timer tick, asking Buildbucket for the build status
[03:16:01.257] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:01.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:23:0) after 1m0s
[03:17:01.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:23:0)
[03:17:01.287] Timer tick, asking Buildbucket for the build status
[03:17:01.576] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:01.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:24:0) after 1m0s
[03:18:01.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:24:0)
[03:18:01.597] Timer tick, asking Buildbucket for the build status
[03:18:01.655] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:01.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:25:0) after 1m0s
[03:19:01.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:25:0)
[03:19:01.672] Timer tick, asking Buildbucket for the build status
[03:19:01.817] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:01.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:26:0) after 1m0s
[03:20:01.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:26:0)
[03:20:01.832] Timer tick, asking Buildbucket for the build status
[03:20:01.916] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:01.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:27:0) after 1m0s
[03:21:01.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:27:0)
[03:21:01.938] Timer tick, asking Buildbucket for the build status
[03:21:02.077] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:02.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:28:0) after 1m0s
[03:22:02.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:28:0)
[03:22:02.098] Timer tick, asking Buildbucket for the build status
[03:22:02.146] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:02.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:29:0) after 1m0s
[03:23:02.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:29:0)
[03:23:02.169] Timer tick, asking Buildbucket for the build status
[03:23:02.277] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:02.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:30:0) after 1m0s
[03:24:02.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:30:0)
[03:24:02.293] Timer tick, asking Buildbucket for the build status
[03:24:02.529] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:02.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:31:0) after 1m0s
[03:25:02.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:31:0)
[03:25:02.553] Timer tick, asking Buildbucket for the build status
[03:25:02.596] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:02.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:32:0) after 1m0s
[03:26:02.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:32:0)
[03:26:02.601] Timer tick, asking Buildbucket for the build status
[03:26:02.665] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:02.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:33:0) after 1m0s
[03:27:02.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:33:0)
[03:27:02.686] Timer tick, asking Buildbucket for the build status
[03:27:02.785] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:02.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:34:0) after 1m0s
[03:28:02.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:34:0)
[03:28:02.803] Timer tick, asking Buildbucket for the build status
[03:28:02.902] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:02.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:35:0) after 1m0s
[03:29:02.891] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:35:0)
[03:29:02.891] Timer tick, asking Buildbucket for the build status
[03:29:03.061] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:03.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:36:0) after 1m0s
[03:30:03.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:36:0)
[03:30:03.079] Timer tick, asking Buildbucket for the build status
[03:30:03.426] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:03.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:37:0) after 1m0s
[03:31:03.445] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:37:0)
[03:31:03.445] Timer tick, asking Buildbucket for the build status
[03:31:03.583] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:03.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:38:0) after 1m0s
[03:32:03.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:38:0)
[03:32:03.607] Timer tick, asking Buildbucket for the build status
[03:32:04.013] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:04.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:39:0) after 1m0s
[03:33:03.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:39:0)
[03:33:03.993] Timer tick, asking Buildbucket for the build status
[03:33:04.056] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:04.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:40:0) after 1m0s
[03:34:04.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:40:0)
[03:34:04.079] Timer tick, asking Buildbucket for the build status
[03:34:04.137] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:04.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:41:0) after 1m0s
[03:35:04.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:41:0)
[03:35:04.182] Timer tick, asking Buildbucket for the build status
[03:35:04.287] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:04.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:42:0) after 1m0s
[03:36:04.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:42:0)
[03:36:04.297] Timer tick, asking Buildbucket for the build status
[03:36:04.490] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:04.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:43:0) after 1m0s
[03:37:04.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:43:0)
[03:37:04.509] Timer tick, asking Buildbucket for the build status
[03:37:04.557] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:04.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:44:0) after 1m0s
[03:38:04.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:44:0)
[03:38:04.595] Timer tick, asking Buildbucket for the build status
[03:38:04.665] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:04.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:45:0) after 1m0s
[03:39:04.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:45:0)
[03:39:04.672] Timer tick, asking Buildbucket for the build status
[03:39:04.750] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:04.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:46:0) after 1m0s
[03:40:04.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:46:0)
[03:40:04.774] Timer tick, asking Buildbucket for the build status
[03:40:04.850] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:04.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:47:0) after 1m0s
[03:41:04.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:47:0)
[03:41:04.857] Timer tick, asking Buildbucket for the build status
[03:41:04.902] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:04.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:48:0) after 1m0s
[03:42:04.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:48:0)
[03:42:04.928] Timer tick, asking Buildbucket for the build status
[03:42:04.990] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:04.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:49:0) after 1m0s
[03:43:05.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:49:0)
[03:43:05.017] Timer tick, asking Buildbucket for the build status
[03:43:05.169] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:05.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:50:0) after 1m0s
[03:44:05.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:50:0)
[03:44:05.145] Timer tick, asking Buildbucket for the build status
[03:44:05.211] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:05.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:51:0) after 1m0s
[03:45:05.234] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:51:0)
[03:45:05.234] Timer tick, asking Buildbucket for the build status
[03:45:05.388] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:05.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:52:0) after 1m0s
[03:46:05.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:52:0)
[03:46:05.414] Timer tick, asking Buildbucket for the build status
[03:46:05.486] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:05.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:53:0) after 1m0s
[03:47:05.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:53:0)
[03:47:05.521] Timer tick, asking Buildbucket for the build status
[03:47:05.562] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:05.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:54:0) after 1m0s
[03:48:05.544] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:54:0)
[03:48:05.544] Timer tick, asking Buildbucket for the build status
[03:48:05.614] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:05.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:55:0) after 1m0s
[03:49:05.635] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:55:0)
[03:49:05.635] Timer tick, asking Buildbucket for the build status
[03:49:05.682] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:05.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:56:0) after 1m0s
[03:50:05.655] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:56:0)
[03:50:05.655] Timer tick, asking Buildbucket for the build status
[03:50:05.722] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:05.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:57:0) after 1m0s
[03:51:05.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:57:0)
[03:51:05.741] Timer tick, asking Buildbucket for the build status
[03:51:05.836] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:05.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:58:0) after 1m0s
[03:52:05.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:58:0)
[03:52:05.864] Timer tick, asking Buildbucket for the build status
[03:52:05.912] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:05.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:59:0) after 1m0s
[03:53:05.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:59:0)
[03:53:05.927] Timer tick, asking Buildbucket for the build status
[03:53:05.987] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:05.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:60:0) after 1m0s
[03:54:06.010] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:60:0)
[03:54:06.010] Timer tick, asking Buildbucket for the build status
[03:54:06.085] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:06.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:61:0) after 1m0s
[03:55:06.130] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:61:0)
[03:55:06.130] Timer tick, asking Buildbucket for the build status
[03:55:06.173] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:06.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:62:0) after 1m0s
[03:56:06.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:62:0)
[03:56:06.199] Timer tick, asking Buildbucket for the build status
[03:56:06.341] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:06.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:63:0) after 1m0s
[03:57:06.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:63:0)
[03:57:06.372] Timer tick, asking Buildbucket for the build status
[03:57:06.427] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:06.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:64:0) after 1m0s
[03:58:06.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:64:0)
[03:58:06.404] Timer tick, asking Buildbucket for the build status
[03:58:06.515] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:06.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:65:0) after 1m0s
[03:59:06.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:65:0)
[03:59:06.519] Timer tick, asking Buildbucket for the build status
[03:59:06.585] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:06.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:66:0) after 1m0s
[04:00:06.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:66:0)
[04:00:06.580] Timer tick, asking Buildbucket for the build status
[04:00:06.704] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:06.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:67:0) after 1m0s
[04:01:06.724] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:67:0)
[04:01:06.724] Timer tick, asking Buildbucket for the build status
[04:01:06.814] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:06.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:68:0) after 1m0s
[04:02:06.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:68:0)
[04:02:06.833] Timer tick, asking Buildbucket for the build status
[04:02:06.877] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:06.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:69:0) after 1m0s
[04:03:06.858] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:69:0)
[04:03:06.858] Timer tick, asking Buildbucket for the build status
[04:03:07.014] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:07.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:70:0) after 1m0s
[04:04:07.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:70:0)
[04:04:07.037] Timer tick, asking Buildbucket for the build status
[04:04:07.129] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:07.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:71:0) after 1m0s
[04:05:07.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:71:0)
[04:05:07.153] Timer tick, asking Buildbucket for the build status
[04:05:07.275] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:07.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:72:0) after 1m0s
[04:06:07.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:72:0)
[04:06:07.296] Timer tick, asking Buildbucket for the build status
[04:06:07.504] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:07.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:73:0) after 1m0s
[04:07:07.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:73:0)
[04:07:07.523] Timer tick, asking Buildbucket for the build status
[04:07:07.566] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:07.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:74:0) after 1m0s
[04:08:07.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:74:0)
[04:08:07.593] Timer tick, asking Buildbucket for the build status
[04:08:07.844] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:07.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:75:0) after 1m0s
[04:09:07.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:75:0)
[04:09:07.865] Timer tick, asking Buildbucket for the build status
[04:09:07.930] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:07.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:76:0) after 1m0s
[04:10:07.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:76:0)
[04:10:07.913] Timer tick, asking Buildbucket for the build status
[04:10:07.957] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:07.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:77:0) after 1m0s
[04:11:07.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:77:0)
[04:11:07.980] Timer tick, asking Buildbucket for the build status
[04:11:08.042] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:08.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:78:0) after 1m0s
[04:12:08.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:78:0)
[04:12:08.019] Timer tick, asking Buildbucket for the build status
[04:12:08.082] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:08.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:79:0) after 1m0s
[04:13:08.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:79:0)
[04:13:08.104] Timer tick, asking Buildbucket for the build status
[04:13:08.166] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:08.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:80:0) after 1m0s
[04:14:08.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:80:0)
[04:14:08.201] Timer tick, asking Buildbucket for the build status
[04:14:08.275] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:08.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:81:0) after 1m0s
[04:15:08.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:81:0)
[04:15:08.295] Timer tick, asking Buildbucket for the build status
[04:15:08.371] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:08.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:82:0) after 1m0s
[04:16:08.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:82:0)
[04:16:08.393] Timer tick, asking Buildbucket for the build status
[04:16:08.480] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:08.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:83:0) after 1m0s
[04:17:08.495] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:83:0)
[04:17:08.495] Timer tick, asking Buildbucket for the build status
[04:17:08.561] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:08.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:84:0) after 1m0s
[04:18:08.585] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:84:0)
[04:18:08.585] Timer tick, asking Buildbucket for the build status
[04:18:08.789] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:08.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:85:0) after 1m0s
[04:19:08.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:85:0)
[04:19:08.779] Timer tick, asking Buildbucket for the build status
[04:19:08.975] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:08.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:86:0) after 1m0s
[04:20:08.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:86:0)
[04:20:08.975] Timer tick, asking Buildbucket for the build status
[04:20:09.124] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:09.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:87:0) after 1m0s
[04:21:09.154] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:87:0)
[04:21:09.154] Timer tick, asking Buildbucket for the build status
[04:21:09.197] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:09.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:88:0) after 1m0s
[04:22:09.220] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:88:0)
[04:22:09.220] Timer tick, asking Buildbucket for the build status
[04:22:09.304] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:09.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:89:0) after 1m0s
[04:23:09.324] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:89:0)
[04:23:09.325] Timer tick, asking Buildbucket for the build status
[04:23:09.387] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:09.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:90:0) after 1m0s
[04:24:09.413] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:90:0)
[04:24:09.413] Timer tick, asking Buildbucket for the build status
[04:24:09.488] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:09.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:91:0) after 1m0s
[04:25:09.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:91:0)
[04:25:09.506] Timer tick, asking Buildbucket for the build status
[04:25:09.572] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:09.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:92:0) after 1m0s
[04:26:09.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:92:0)
[04:26:09.598] Timer tick, asking Buildbucket for the build status
[04:26:09.654] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:09.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:93:0) after 1m0s
[04:27:09.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:93:0)
[04:27:09.679] Timer tick, asking Buildbucket for the build status
[04:27:09.743] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:09.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:94:0) after 1m0s
[04:28:09.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:94:0)
[04:28:09.777] Timer tick, asking Buildbucket for the build status
[04:28:09.893] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:09.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:95:0) after 1m0s
[04:29:09.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:95:0)
[04:29:09.925] Timer tick, asking Buildbucket for the build status
[04:29:09.969] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:09.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:96:0) after 1m0s
[04:30:09.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:96:0)
[04:30:09.968] Timer tick, asking Buildbucket for the build status
[04:30:10.033] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:10.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:97:0) after 1m0s
[04:31:10.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:97:0)
[04:31:10.109] Timer tick, asking Buildbucket for the build status
[04:31:10.146] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:10.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:98:0) after 1m0s
[04:32:10.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:98:0)
[04:32:10.189] Timer tick, asking Buildbucket for the build status
[04:32:10.303] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:10.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:99:0) after 1m0s
[04:33:10.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:99:0)
[04:33:10.319] Timer tick, asking Buildbucket for the build status
[04:33:10.414] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:10.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:100:0) after 1m0s
[04:34:10.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:100:0)
[04:34:10.446] Timer tick, asking Buildbucket for the build status
[04:34:10.878] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:10.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:101:0) after 1m0s
[04:35:10.861] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:101:0)
[04:35:10.861] Timer tick, asking Buildbucket for the build status
[04:35:10.924] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:10.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:102:0) after 1m0s
[04:36:10.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:102:0)
[04:36:10.957] Timer tick, asking Buildbucket for the build status
[04:36:11.141] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:11.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:103:0) after 1m0s
[04:37:11.165] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:103:0)
[04:37:11.165] Timer tick, asking Buildbucket for the build status
[04:37:11.291] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:11.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:104:0) after 1m0s
[04:38:11.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:104:0)
[04:38:11.332] Timer tick, asking Buildbucket for the build status
[04:38:11.462] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:11.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:105:0) after 1m0s
[04:39:11.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:105:0)
[04:39:11.488] Timer tick, asking Buildbucket for the build status
[04:39:11.610] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:11.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:106:0) after 1m0s
[04:40:11.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:106:0)
[04:40:11.609] Timer tick, asking Buildbucket for the build status
[04:40:11.714] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:11.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:107:0) after 1m0s
[04:41:11.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:107:0)
[04:41:11.734] Timer tick, asking Buildbucket for the build status
[04:41:11.979] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:11.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:108:0) after 1m0s
[04:42:12.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:108:0)
[04:42:12.083] Timer tick, asking Buildbucket for the build status
[04:42:12.145] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:12.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:109:0) after 1m0s
[04:43:12.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:109:0)
[04:43:12.134] Timer tick, asking Buildbucket for the build status
[04:43:12.213] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:12.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:110:0) after 1m0s
[04:44:12.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:110:0)
[04:44:12.236] Timer tick, asking Buildbucket for the build status
[04:44:12.307] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:12.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:111:0) after 1m0s
[04:45:12.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:111:0)
[04:45:12.321] Timer tick, asking Buildbucket for the build status
[04:45:12.385] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:12.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:112:0) after 1m0s
[04:46:12.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:112:0)
[04:46:12.464] Timer tick, asking Buildbucket for the build status
[04:46:12.543] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:12.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:113:0) after 1m0s
[04:47:12.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:113:0)
[04:47:12.567] Timer tick, asking Buildbucket for the build status
[04:47:12.628] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:12.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:114:0) after 1m0s
[04:48:12.645] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:114:0)
[04:48:12.645] Timer tick, asking Buildbucket for the build status
[04:48:12.769] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:12.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:115:0) after 1m0s
[04:49:12.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:115:0)
[04:49:12.745] Timer tick, asking Buildbucket for the build status
[04:49:12.807] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:12.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:116:0) after 1m0s
[04:50:12.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:116:0)
[04:50:12.794] Timer tick, asking Buildbucket for the build status
[04:50:12.856] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:12.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:117:0) after 1m0s
[04:51:12.931] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:117:0)
[04:51:12.931] Timer tick, asking Buildbucket for the build status
[04:51:13.057] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:13.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:118:0) after 1m0s
[04:52:13.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:118:0)
[04:52:13.083] Timer tick, asking Buildbucket for the build status
[04:52:13.141] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:13.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:119:0) after 1m0s
[04:53:13.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:119:0)
[04:53:13.167] Timer tick, asking Buildbucket for the build status
[04:53:13.228] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:13.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:120:0) after 1m0s
[04:54:13.272] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:120:0)
[04:54:13.272] Timer tick, asking Buildbucket for the build status
[04:54:13.394] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:13.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:121:0) after 1m0s
[04:55:13.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:121:0)
[04:55:13.416] Timer tick, asking Buildbucket for the build status
[04:55:13.597] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:13.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:122:0) after 1m0s
[04:56:13.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:122:0)
[04:56:13.649] Timer tick, asking Buildbucket for the build status
[04:56:13.713] Build 8917686802010523760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:13.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083116864003167840:123:0) after 1m0s
[04:56:47.513] Received PubSub notification, asking Buildbucket for the build status
[04:56:47.552] Build 8917686802010523760: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:56:47.552] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553835407246194",
"created_by": "project:fuchsia",
"created_ts": "1553828157375379",
"failure_reason": "BUILD_FAILURE",
"id": "8917686802010523760",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"2a3f42c90d4ba2860e039e4de92a75c75cfdc351\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-sjwt\", \"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\": \"2a3f42c90d4ba2860e039e4de92a75c75cfdc351\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-sjwt\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4226-cfe6277\"], \"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": "1553828173160507",
"status": "COMPLETED",
"status_changed_ts": "1553828174328536",
"tags": [
"builder:clang",
"buildset:commit/git/2a3f42c90d4ba2860e039e4de92a75c75cfdc351",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/2a3f42c90d4ba2860e039e4de92a75c75cfdc351",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083116864003167840",
"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/8917686802010523760/+/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:43e031a591d3d710",
"user_agent:luci-scheduler"
],
"updated_ts": "1553835407290372",
"url": "https://ci.chromium.org/b/8917686802010523760",
"utcnow_ts": "1553835407544832"
}
}
[04:56:47.552] Invocation finished in 2h0m51.461226485s with status FAILED