[17:24:56.529] New invocation is queued and will start shortly
[17:24:57.679] Starting the invocation (attempt 1)
[17:24:57.717] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:24:57.717] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:24:57.717] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9084239951371597392",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"6db6b56a5c8fc6dd2ab7d50d289e292a14121cd4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9084239951371597392",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/6db6b56a5c8fc6dd2ab7d50d289e292a14121cd4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/6db6b56a5c8fc6dd2ab7d50d289e292a14121cd4",
"gitiles_ref:refs/heads/master"
]
}
[17:24:58.945] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1552757097823022",
"id": "8918809889351630144",
"lease_expiration_ts": "1552883098635435",
"lease_key": "1828792249",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"6db6b56a5c8fc6dd2ab7d50d289e292a14121cd4\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552757098788850",
"tags": [
"builder:clang",
"buildset:commit/git/6db6b56a5c8fc6dd2ab7d50d289e292a14121cd4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/6db6b56a5c8fc6dd2ab7d50d289e292a14121cd4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084239951371597392",
"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/8918809889351630144/+/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:43a05a8f6e1ae310",
"user_agent:luci-scheduler"
],
"updated_ts": "1552757098788928",
"url": "https://ci.chromium.org/b/8918809889351630144",
"utcnow_ts": "1552757098936199"
}
}
[17:24:58.945] Task URL: https://ci.chromium.org/b/8918809889351630144
[17:24:58.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:2:0) after 1m0s
[17:25:10.595] Received PubSub notification, asking Buildbucket for the build status
[17:25:10.676] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:58.940] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:2:0)
[17:25:58.940] Timer tick, asking Buildbucket for the build status
[17:25:59.271] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:59.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:4:0) after 1m0s
[17:26:59.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:4:0)
[17:26:59.297] Timer tick, asking Buildbucket for the build status
[17:26:59.394] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:59.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:5:0) after 1m0s
[17:27:59.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:5:0)
[17:27:59.397] Timer tick, asking Buildbucket for the build status
[17:27:59.496] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:59.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:6:0) after 1m0s
[17:28:59.520] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:6:0)
[17:28:59.520] Timer tick, asking Buildbucket for the build status
[17:28:59.587] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:59.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:7:0) after 1m0s
[17:29:59.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:7:0)
[17:29:59.564] Timer tick, asking Buildbucket for the build status
[17:29:59.667] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:59.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:8:0) after 1m0s
[17:30:59.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:8:0)
[17:30:59.688] Timer tick, asking Buildbucket for the build status
[17:30:59.744] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:59.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:9:0) after 1m0s
[17:31:59.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:9:0)
[17:31:59.762] Timer tick, asking Buildbucket for the build status
[17:31:59.869] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:59.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:10:0) after 1m0s
[17:32:59.872] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:10:0)
[17:32:59.872] Timer tick, asking Buildbucket for the build status
[17:32:59.934] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:59.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:11:0) after 1m0s
[17:33:59.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:11:0)
[17:33:59.950] Timer tick, asking Buildbucket for the build status
[17:34:00.168] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:00.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:12:0) after 1m0s
[17:35:00.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:12:0)
[17:35:00.202] Timer tick, asking Buildbucket for the build status
[17:35:00.262] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:00.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:13:0) after 1m0s
[17:36:00.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:13:0)
[17:36:00.278] Timer tick, asking Buildbucket for the build status
[17:36:00.395] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:00.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:14:0) after 1m0s
[17:37:00.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:14:0)
[17:37:00.411] Timer tick, asking Buildbucket for the build status
[17:37:00.477] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:00.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:15:0) after 1m0s
[17:38:00.495] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:15:0)
[17:38:00.495] Timer tick, asking Buildbucket for the build status
[17:38:00.687] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:00.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:16:0) after 1m0s
[17:39:00.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:16:0)
[17:39:00.705] Timer tick, asking Buildbucket for the build status
[17:39:02.021] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:02.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:17:0) after 1m0s
[17:40:02.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:17:0)
[17:40:02.013] Timer tick, asking Buildbucket for the build status
[17:40:02.072] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:02.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:18:0) after 1m0s
[17:41:02.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:18:0)
[17:41:02.117] Timer tick, asking Buildbucket for the build status
[17:41:02.315] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:02.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:19:0) after 1m0s
[17:42:02.334] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:19:0)
[17:42:02.334] Timer tick, asking Buildbucket for the build status
[17:42:02.399] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:02.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:20:0) after 1m0s
[17:43:02.381] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:20:0)
[17:43:02.381] Timer tick, asking Buildbucket for the build status
[17:43:02.541] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:02.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:21:0) after 1m0s
[17:44:02.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:21:0)
[17:44:02.563] Timer tick, asking Buildbucket for the build status
[17:44:02.707] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:02.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:22:0) after 1m0s
[17:45:02.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:22:0)
[17:45:02.692] Timer tick, asking Buildbucket for the build status
[17:45:02.858] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:02.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:23:0) after 1m0s
[17:46:02.874] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:23:0)
[17:46:02.874] Timer tick, asking Buildbucket for the build status
[17:46:03.009] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:03.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:24:0) after 1m0s
[17:47:03.014] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:24:0)
[17:47:03.014] Timer tick, asking Buildbucket for the build status
[17:47:09.181] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:09.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:25:0) after 1m0s
[17:48:09.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:25:0)
[17:48:09.206] Timer tick, asking Buildbucket for the build status
[17:48:09.409] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:09.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:26:0) after 1m0s
[17:49:09.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:26:0)
[17:49:09.426] Timer tick, asking Buildbucket for the build status
[17:49:09.516] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:09.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:27:0) after 1m0s
[17:50:09.532] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:27:0)
[17:50:09.532] Timer tick, asking Buildbucket for the build status
[17:50:09.589] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:09.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:28:0) after 1m0s
[17:51:09.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:28:0)
[17:51:09.607] Timer tick, asking Buildbucket for the build status
[17:51:09.675] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:09.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:29:0) after 1m0s
[17:52:09.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:29:0)
[17:52:09.665] Timer tick, asking Buildbucket for the build status
[17:52:09.785] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:09.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:30:0) after 1m0s
[17:53:09.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:30:0)
[17:53:09.803] Timer tick, asking Buildbucket for the build status
[17:53:10.047] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:10.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:31:0) after 1m0s
[17:54:10.033] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:31:0)
[17:54:10.033] Timer tick, asking Buildbucket for the build status
[17:54:10.115] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:10.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:32:0) after 1m0s
[17:55:10.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:32:0)
[17:55:10.138] Timer tick, asking Buildbucket for the build status
[17:55:10.217] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:10.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:33:0) after 1m0s
[17:56:10.237] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:33:0)
[17:56:10.237] Timer tick, asking Buildbucket for the build status
[17:56:10.307] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:10.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:34:0) after 1m0s
[17:57:10.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:34:0)
[17:57:10.308] Timer tick, asking Buildbucket for the build status
[17:57:10.493] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:10.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:35:0) after 1m0s
[17:58:10.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:35:0)
[17:58:10.524] Timer tick, asking Buildbucket for the build status
[17:58:10.618] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:10.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:36:0) after 1m0s
[17:59:10.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:36:0)
[17:59:10.604] Timer tick, asking Buildbucket for the build status
[17:59:10.825] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:10.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:37:0) after 1m0s
[18:00:10.844] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:37:0)
[18:00:10.844] Timer tick, asking Buildbucket for the build status
[18:00:11.094] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:11.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:38:0) after 1m0s
[18:01:11.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:38:0)
[18:01:11.107] Timer tick, asking Buildbucket for the build status
[18:01:11.375] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:11.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:39:0) after 1m0s
[18:02:11.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:39:0)
[18:02:11.395] Timer tick, asking Buildbucket for the build status
[18:02:11.459] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:11.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:40:0) after 1m0s
[18:03:11.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:40:0)
[18:03:11.477] Timer tick, asking Buildbucket for the build status
[18:03:11.605] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:11.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:41:0) after 1m0s
[18:04:11.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:41:0)
[18:04:11.591] Timer tick, asking Buildbucket for the build status
[18:04:11.760] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:11.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:42:0) after 1m0s
[18:05:11.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:42:0)
[18:05:11.783] Timer tick, asking Buildbucket for the build status
[18:05:11.974] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:11.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:43:0) after 1m0s
[18:06:11.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:43:0)
[18:06:11.987] Timer tick, asking Buildbucket for the build status
[18:06:12.103] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:12.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:44:0) after 1m0s
[18:07:12.132] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:44:0)
[18:07:12.132] Timer tick, asking Buildbucket for the build status
[18:07:12.280] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:12.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:45:0) after 1m0s
[18:08:12.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:45:0)
[18:08:12.305] Timer tick, asking Buildbucket for the build status
[18:08:12.380] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:12.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:46:0) after 1m0s
[18:09:12.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:46:0)
[18:09:12.379] Timer tick, asking Buildbucket for the build status
[18:09:12.451] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:12.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:47:0) after 1m0s
[18:10:12.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:47:0)
[18:10:12.472] Timer tick, asking Buildbucket for the build status
[18:10:12.609] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:12.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:48:0) after 1m0s
[18:11:12.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:48:0)
[18:11:12.697] Timer tick, asking Buildbucket for the build status
[18:11:12.742] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:12.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:49:0) after 1m0s
[18:12:12.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:49:0)
[18:12:12.715] Timer tick, asking Buildbucket for the build status
[18:12:12.865] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:12.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:50:0) after 1m0s
[18:13:12.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:50:0)
[18:13:12.871] Timer tick, asking Buildbucket for the build status
[18:13:12.923] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:12.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:51:0) after 1m0s
[18:14:12.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:51:0)
[18:14:12.945] Timer tick, asking Buildbucket for the build status
[18:14:13.011] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:13.011] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:52:0) after 1m0s
[18:15:13.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:52:0)
[18:15:13.034] Timer tick, asking Buildbucket for the build status
[18:15:13.091] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:13.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:53:0) after 1m0s
[18:16:13.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:53:0)
[18:16:13.097] Timer tick, asking Buildbucket for the build status
[18:16:13.162] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:13.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:54:0) after 1m0s
[18:17:13.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:54:0)
[18:17:13.197] Timer tick, asking Buildbucket for the build status
[18:17:13.294] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:13.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:55:0) after 1m0s
[18:18:13.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:55:0)
[18:18:13.308] Timer tick, asking Buildbucket for the build status
[18:18:13.393] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:13.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:56:0) after 1m0s
[18:19:13.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:56:0)
[18:19:13.399] Timer tick, asking Buildbucket for the build status
[18:19:13.546] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:13.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:57:0) after 1m0s
[18:20:13.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:57:0)
[18:20:13.590] Timer tick, asking Buildbucket for the build status
[18:20:13.773] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:13.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:58:0) after 1m0s
[18:21:13.747] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:58:0)
[18:21:13.747] Timer tick, asking Buildbucket for the build status
[18:21:13.813] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:13.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:59:0) after 1m0s
[18:22:13.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:59:0)
[18:22:13.832] Timer tick, asking Buildbucket for the build status
[18:22:13.889] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:13.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:60:0) after 1m0s
[18:23:13.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:60:0)
[18:23:13.911] Timer tick, asking Buildbucket for the build status
[18:23:13.975] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:13.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:61:0) after 1m0s
[18:24:13.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:61:0)
[18:24:13.978] Timer tick, asking Buildbucket for the build status
[18:24:14.046] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:14.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:62:0) after 1m0s
[18:25:14.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:62:0)
[18:25:14.064] Timer tick, asking Buildbucket for the build status
[18:25:14.174] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:14.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:63:0) after 1m0s
[18:26:14.179] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:63:0)
[18:26:14.179] Timer tick, asking Buildbucket for the build status
[18:26:14.241] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:14.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:64:0) after 1m0s
[18:27:14.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:64:0)
[18:27:14.259] Timer tick, asking Buildbucket for the build status
[18:27:14.403] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:14.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:65:0) after 1m0s
[18:28:14.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:65:0)
[18:28:14.420] Timer tick, asking Buildbucket for the build status
[18:28:14.488] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:14.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:66:0) after 1m0s
[18:29:14.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:66:0)
[18:29:14.489] Timer tick, asking Buildbucket for the build status
[18:29:14.549] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:14.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:67:0) after 1m0s
[18:30:14.565] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:67:0)
[18:30:14.565] Timer tick, asking Buildbucket for the build status
[18:30:14.771] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:14.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:68:0) after 1m0s
[18:31:14.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:68:0)
[18:31:14.792] Timer tick, asking Buildbucket for the build status
[18:31:14.850] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:14.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:69:0) after 1m0s
[18:32:14.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:69:0)
[18:32:14.873] Timer tick, asking Buildbucket for the build status
[18:32:14.993] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:14.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:70:0) after 1m0s
[18:33:14.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:70:0)
[18:33:14.997] Timer tick, asking Buildbucket for the build status
[18:33:15.063] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:15.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:71:0) after 1m0s
[18:34:15.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:71:0)
[18:34:15.057] Timer tick, asking Buildbucket for the build status
[18:34:15.114] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:15.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:72:0) after 1m0s
[18:35:15.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:72:0)
[18:35:15.136] Timer tick, asking Buildbucket for the build status
[18:35:15.189] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:15.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:73:0) after 1m0s
[18:36:15.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:73:0)
[18:36:15.193] Timer tick, asking Buildbucket for the build status
[18:36:15.265] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:15.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:74:0) after 1m0s
[18:37:15.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:74:0)
[18:37:15.286] Timer tick, asking Buildbucket for the build status
[18:37:15.549] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:15.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:75:0) after 1m0s
[18:38:15.520] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:75:0)
[18:38:15.520] Timer tick, asking Buildbucket for the build status
[18:38:15.588] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:15.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:76:0) after 1m0s
[18:39:15.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:76:0)
[18:39:15.561] Timer tick, asking Buildbucket for the build status
[18:39:15.625] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:15.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:77:0) after 1m0s
[18:40:15.645] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:77:0)
[18:40:15.645] Timer tick, asking Buildbucket for the build status
[18:40:15.762] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:15.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:78:0) after 1m0s
[18:41:15.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:78:0)
[18:41:15.781] Timer tick, asking Buildbucket for the build status
[18:41:15.868] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:15.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:79:0) after 1m0s
[18:42:15.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:79:0)
[18:42:15.864] Timer tick, asking Buildbucket for the build status
[18:42:15.921] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:15.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:80:0) after 1m0s
[18:43:15.940] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:80:0)
[18:43:15.940] Timer tick, asking Buildbucket for the build status
[18:43:16.013] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:16.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:81:0) after 1m0s
[18:44:16.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:81:0)
[18:44:16.034] Timer tick, asking Buildbucket for the build status
[18:44:16.251] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:16.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:82:0) after 1m0s
[18:45:16.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:82:0)
[18:45:16.273] Timer tick, asking Buildbucket for the build status
[18:45:16.484] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:16.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:83:0) after 1m0s
[18:46:16.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:83:0)
[18:46:16.469] Timer tick, asking Buildbucket for the build status
[18:46:16.554] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:16.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:84:0) after 1m0s
[18:47:16.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:84:0)
[18:47:16.576] Timer tick, asking Buildbucket for the build status
[18:47:16.645] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:16.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:85:0) after 1m0s
[18:48:16.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:85:0)
[18:48:16.704] Timer tick, asking Buildbucket for the build status
[18:48:16.807] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:16.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:86:0) after 1m0s
[18:49:16.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:86:0)
[18:49:16.805] Timer tick, asking Buildbucket for the build status
[18:49:16.862] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:16.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:87:0) after 1m0s
[18:50:16.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:87:0)
[18:50:16.865] Timer tick, asking Buildbucket for the build status
[18:50:16.942] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:16.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:88:0) after 1m0s
[18:51:16.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:88:0)
[18:51:16.920] Timer tick, asking Buildbucket for the build status
[18:51:16.961] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:16.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:89:0) after 1m0s
[18:52:16.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:89:0)
[18:52:16.936] Timer tick, asking Buildbucket for the build status
[18:52:17.112] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:17.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:90:0) after 1m0s
[18:53:17.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:90:0)
[18:53:17.101] Timer tick, asking Buildbucket for the build status
[18:53:17.371] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:17.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:91:0) after 1m0s
[18:54:17.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:91:0)
[18:54:17.382] Timer tick, asking Buildbucket for the build status
[18:54:17.458] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:17.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:92:0) after 1m0s
[18:55:17.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:92:0)
[18:55:17.460] Timer tick, asking Buildbucket for the build status
[18:55:17.512] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:17.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:93:0) after 1m0s
[18:56:17.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:93:0)
[18:56:17.507] Timer tick, asking Buildbucket for the build status
[18:56:17.593] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:17.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:94:0) after 1m0s
[18:57:17.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:94:0)
[18:57:17.580] Timer tick, asking Buildbucket for the build status
[18:57:17.662] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:17.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:95:0) after 1m0s
[18:58:17.638] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:95:0)
[18:58:17.638] Timer tick, asking Buildbucket for the build status
[18:58:17.693] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:17.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:96:0) after 1m0s
[18:59:17.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:96:0)
[18:59:17.676] Timer tick, asking Buildbucket for the build status
[18:59:17.926] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:17.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:97:0) after 1m0s
[19:00:17.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:97:0)
[19:00:17.978] Timer tick, asking Buildbucket for the build status
[19:00:18.170] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:18.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:98:0) after 1m0s
[19:01:18.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:98:0)
[19:01:18.191] Timer tick, asking Buildbucket for the build status
[19:01:18.334] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:18.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:99:0) after 1m0s
[19:02:18.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:99:0)
[19:02:18.332] Timer tick, asking Buildbucket for the build status
[19:02:18.406] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:18.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:100:0) after 1m0s
[19:03:18.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:100:0)
[19:03:18.433] Timer tick, asking Buildbucket for the build status
[19:03:18.493] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:18.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:101:0) after 1m0s
[19:04:18.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:101:0)
[19:04:18.518] Timer tick, asking Buildbucket for the build status
[19:04:18.585] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:18.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:102:0) after 1m0s
[19:05:18.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:102:0)
[19:05:18.602] Timer tick, asking Buildbucket for the build status
[19:05:18.899] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:18.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:103:0) after 1m0s
[19:06:18.895] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:103:0)
[19:06:18.895] Timer tick, asking Buildbucket for the build status
[19:06:19.034] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:19.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:104:0) after 1m0s
[19:07:19.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:104:0)
[19:07:19.026] Timer tick, asking Buildbucket for the build status
[19:07:19.155] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:19.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:105:0) after 1m0s
[19:08:19.175] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:105:0)
[19:08:19.175] Timer tick, asking Buildbucket for the build status
[19:08:19.397] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:19.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:106:0) after 1m0s
[19:09:19.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:106:0)
[19:09:19.492] Timer tick, asking Buildbucket for the build status
[19:09:19.565] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:19.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:107:0) after 1m0s
[19:10:19.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:107:0)
[19:10:19.584] Timer tick, asking Buildbucket for the build status
[19:10:19.717] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:19.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:108:0) after 1m0s
[19:11:19.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:108:0)
[19:11:19.737] Timer tick, asking Buildbucket for the build status
[19:11:19.893] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:19.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:109:0) after 1m0s
[19:12:19.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:109:0)
[19:12:19.914] Timer tick, asking Buildbucket for the build status
[19:12:20.014] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:20.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:110:0) after 1m0s
[19:13:19.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:110:0)
[19:13:19.993] Timer tick, asking Buildbucket for the build status
[19:13:20.084] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:20.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:111:0) after 1m0s
[19:14:20.119] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:111:0)
[19:14:20.119] Timer tick, asking Buildbucket for the build status
[19:14:20.180] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:20.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:112:0) after 1m0s
[19:15:20.205] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:112:0)
[19:15:20.205] Timer tick, asking Buildbucket for the build status
[19:15:20.379] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:20.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:113:0) after 1m0s
[19:16:20.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:113:0)
[19:16:20.412] Timer tick, asking Buildbucket for the build status
[19:16:20.490] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:20.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:114:0) after 1m0s
[19:17:20.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:114:0)
[19:17:20.475] Timer tick, asking Buildbucket for the build status
[19:17:20.550] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:20.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:115:0) after 1m0s
[19:18:20.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:115:0)
[19:18:20.571] Timer tick, asking Buildbucket for the build status
[19:18:20.765] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:20.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:116:0) after 1m0s
[19:19:20.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:116:0)
[19:19:20.786] Timer tick, asking Buildbucket for the build status
[19:19:20.858] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:20.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:117:0) after 1m0s
[19:20:20.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:117:0)
[19:20:20.862] Timer tick, asking Buildbucket for the build status
[19:20:20.923] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:20.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:118:0) after 1m0s
[19:21:20.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:118:0)
[19:21:20.910] Timer tick, asking Buildbucket for the build status
[19:21:20.968] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:20.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:119:0) after 1m0s
[19:22:20.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:119:0)
[19:22:20.990] Timer tick, asking Buildbucket for the build status
[19:22:21.187] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:21.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:120:0) after 1m0s
[19:23:21.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:120:0)
[19:23:21.167] Timer tick, asking Buildbucket for the build status
[19:23:21.225] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:21.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:121:0) after 1m0s
[19:24:21.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:121:0)
[19:24:21.245] Timer tick, asking Buildbucket for the build status
[19:24:21.307] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:21.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:122:0) after 1m0s
[19:25:21.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:122:0)
[19:25:21.331] Timer tick, asking Buildbucket for the build status
[19:25:21.398] Build 8918809889351630144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:21.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084239951371597392:123:0) after 1m0s
[19:25:39.908] Received PubSub notification, asking Buildbucket for the build status
[19:25:39.954] Build 8918809889351630144: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:25:39.954] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1552764339275981",
"created_by": "project:fuchsia",
"created_ts": "1552757097823022",
"failure_reason": "BUILD_FAILURE",
"id": "8918809889351630144",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"6db6b56a5c8fc6dd2ab7d50d289e292a14121cd4\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"image\": [\"fuchsia-debian-9-drawfork-v20181107-ee01d6c\"], \"kvm\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4201-b04f7f8\"], \"machine_type\": [\"n1-standard-1\"], \"gpu\": [\"none\"], \"cores\": [\"1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"id\": [\"fuchsia-debian-1-03c1d9de-us-central1-b-nskx\"], \"pool\": [\"luci.fuchsia.prod\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"Failure buildbucket.run\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"buildername\": \"clang\", \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"recipe\": \"buildbucket_trigger\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"timeout\": 7200.0, \"path_config\": \"generic\", \"bot_id\": \"fuchsia-debian-1-03c1d9de-us-central1-b-nskx\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"revision\": \"6db6b56a5c8fc6dd2ab7d50d289e292a14121cd4\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1552757109760099",
"status": "COMPLETED",
"status_changed_ts": "1552764339687076",
"tags": [
"builder:clang",
"buildset:commit/git/6db6b56a5c8fc6dd2ab7d50d289e292a14121cd4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/6db6b56a5c8fc6dd2ab7d50d289e292a14121cd4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084239951371597392",
"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/8918809889351630144/+/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:43a05a8f6e1ae310",
"user_agent:luci-scheduler"
],
"updated_ts": "1552764339690892",
"url": "https://ci.chromium.org/b/8918809889351630144",
"utcnow_ts": "1552764339945194"
}
}
[19:25:39.954] Invocation finished in 2h0m43.440421261s with status FAILED