[20:23:14.081] New invocation is queued and will start shortly
[20:23:15.161] Starting the invocation (attempt 1)
[20:23:15.202] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:23:15.202] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:23:15.202] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081782616084256240",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"65132e2118140200dc57e2df9d8713dd07e72119\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081782616084256240",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/65132e2118140200dc57e2df9d8713dd07e72119",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/65132e2118140200dc57e2df9d8713dd07e72119",
"gitiles_ref:refs/heads/master"
]
}
[20:23:16.153] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555100595330790",
"id": "8916352554109645920",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"65132e2118140200dc57e2df9d8713dd07e72119\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555100595951168",
"tags": [
"builder:clang",
"buildset:commit/git/65132e2118140200dc57e2df9d8713dd07e72119",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/65132e2118140200dc57e2df9d8713dd07e72119",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081782616084256240",
"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/8916352554109645920/+/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": "1555100595951277",
"url": "https://ci.chromium.org/b/8916352554109645920",
"utcnow_ts": "1555100596144555"
}
}
[20:23:16.153] Task URL: https://ci.chromium.org/b/8916352554109645920
[20:23:16.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:2:0) after 1m0s
[20:23:40.767] Received PubSub notification, asking Buildbucket for the build status
[20:23:40.948] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:16.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:2:0)
[20:24:16.131] Timer tick, asking Buildbucket for the build status
[20:24:16.314] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:16.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:4:0) after 1m0s
[20:25:16.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:4:0)
[20:25:16.330] Timer tick, asking Buildbucket for the build status
[20:25:16.666] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:16.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:5:0) after 1m0s
[20:26:16.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:5:0)
[20:26:16.684] Timer tick, asking Buildbucket for the build status
[20:26:16.743] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:16.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:6:0) after 1m0s
[20:27:16.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:6:0)
[20:27:16.761] Timer tick, asking Buildbucket for the build status
[20:27:16.801] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:16.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:7:0) after 1m0s
[20:28:16.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:7:0)
[20:28:16.777] Timer tick, asking Buildbucket for the build status
[20:28:16.874] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:16.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:8:0) after 1m0s
[20:29:16.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:8:0)
[20:29:16.897] Timer tick, asking Buildbucket for the build status
[20:29:16.938] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:16.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:9:0) after 1m0s
[20:30:16.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:9:0)
[20:30:16.965] Timer tick, asking Buildbucket for the build status
[20:30:17.008] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:17.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:10:0) after 1m0s
[20:31:16.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:10:0)
[20:31:16.987] Timer tick, asking Buildbucket for the build status
[20:31:17.117] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:17.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:11:0) after 1m0s
[20:32:17.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:11:0)
[20:32:17.138] Timer tick, asking Buildbucket for the build status
[20:32:17.205] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:17.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:12:0) after 1m0s
[20:33:17.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:12:0)
[20:33:17.224] Timer tick, asking Buildbucket for the build status
[20:33:17.266] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:17.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:13:0) after 1m0s
[20:34:17.256] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:13:0)
[20:34:17.256] Timer tick, asking Buildbucket for the build status
[20:34:17.512] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:17.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:14:0) after 1m0s
[20:35:17.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:14:0)
[20:35:17.517] Timer tick, asking Buildbucket for the build status
[20:35:18.109] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:18.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:15:0) after 1m0s
[20:36:18.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:15:0)
[20:36:18.109] Timer tick, asking Buildbucket for the build status
[20:36:18.159] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:18.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:16:0) after 1m0s
[20:37:18.205] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:16:0)
[20:37:18.205] Timer tick, asking Buildbucket for the build status
[20:37:18.288] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:18.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:17:0) after 1m0s
[20:38:18.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:17:0)
[20:38:18.315] Timer tick, asking Buildbucket for the build status
[20:38:18.597] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:18.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:18:0) after 1m0s
[20:39:18.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:18:0)
[20:39:18.591] Timer tick, asking Buildbucket for the build status
[20:39:18.630] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:18.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:19:0) after 1m0s
[20:40:18.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:19:0)
[20:40:18.651] Timer tick, asking Buildbucket for the build status
[20:40:18.706] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:18.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:20:0) after 1m0s
[20:41:18.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:20:0)
[20:41:18.692] Timer tick, asking Buildbucket for the build status
[20:41:18.805] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:18.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:21:0) after 1m0s
[20:42:18.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:21:0)
[20:42:18.790] Timer tick, asking Buildbucket for the build status
[20:42:18.964] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:18.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:22:0) after 1m0s
[20:43:18.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:22:0)
[20:43:18.975] Timer tick, asking Buildbucket for the build status
[20:43:19.035] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:19.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:23:0) after 1m0s
[20:44:19.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:23:0)
[20:44:19.053] Timer tick, asking Buildbucket for the build status
[20:44:19.097] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:19.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:24:0) after 1m0s
[20:45:19.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:24:0)
[20:45:19.122] Timer tick, asking Buildbucket for the build status
[20:45:19.187] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:19.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:25:0) after 1m0s
[20:46:19.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:25:0)
[20:46:19.228] Timer tick, asking Buildbucket for the build status
[20:46:20.884] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:20.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:26:0) after 1m0s
[20:47:20.903] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:26:0)
[20:47:20.903] Timer tick, asking Buildbucket for the build status
[20:47:21.094] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:21.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:27:0) after 1m0s
[20:48:21.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:27:0)
[20:48:21.113] Timer tick, asking Buildbucket for the build status
[20:48:21.278] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:21.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:28:0) after 1m0s
[20:49:21.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:28:0)
[20:49:21.325] Timer tick, asking Buildbucket for the build status
[20:49:21.372] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:21.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:29:0) after 1m0s
[20:50:21.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:29:0)
[20:50:21.391] Timer tick, asking Buildbucket for the build status
[20:50:21.531] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:21.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:30:0) after 1m0s
[20:51:21.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:30:0)
[20:51:21.551] Timer tick, asking Buildbucket for the build status
[20:51:21.598] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:21.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:31:0) after 1m0s
[20:52:21.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:31:0)
[20:52:21.580] Timer tick, asking Buildbucket for the build status
[20:52:21.687] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:21.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:32:0) after 1m0s
[20:53:21.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:32:0)
[20:53:21.716] Timer tick, asking Buildbucket for the build status
[20:53:21.879] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:21.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:33:0) after 1m0s
[20:54:21.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:33:0)
[20:54:21.917] Timer tick, asking Buildbucket for the build status
[20:54:22.055] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:22.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:34:0) after 1m0s
[20:55:22.078] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:34:0)
[20:55:22.078] Timer tick, asking Buildbucket for the build status
[20:55:22.388] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:22.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:35:0) after 1m0s
[20:56:22.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:35:0)
[20:56:22.414] Timer tick, asking Buildbucket for the build status
[20:56:22.456] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:22.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:36:0) after 1m0s
[20:57:22.467] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:36:0)
[20:57:22.467] Timer tick, asking Buildbucket for the build status
[20:57:22.568] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:22.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:37:0) after 1m0s
[20:58:22.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:37:0)
[20:58:22.589] Timer tick, asking Buildbucket for the build status
[20:58:22.650] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:22.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:38:0) after 1m0s
[20:59:22.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:38:0)
[20:59:22.673] Timer tick, asking Buildbucket for the build status
[20:59:22.854] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:22.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:39:0) after 1m0s
[21:00:22.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:39:0)
[21:00:22.882] Timer tick, asking Buildbucket for the build status
[21:00:22.930] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:22.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:40:0) after 1m0s
[21:01:22.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:40:0)
[21:01:22.948] Timer tick, asking Buildbucket for the build status
[21:01:23.024] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:23.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:41:0) after 1m0s
[21:02:23.050] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:41:0)
[21:02:23.050] Timer tick, asking Buildbucket for the build status
[21:02:23.158] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:23.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:42:0) after 1m0s
[21:03:23.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:42:0)
[21:03:23.182] Timer tick, asking Buildbucket for the build status
[21:03:23.355] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:23.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:43:0) after 1m0s
[21:04:23.366] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:43:0)
[21:04:23.366] Timer tick, asking Buildbucket for the build status
[21:04:23.409] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:23.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:44:0) after 1m0s
[21:05:23.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:44:0)
[21:05:23.383] Timer tick, asking Buildbucket for the build status
[21:05:23.444] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:23.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:45:0) after 1m0s
[21:06:23.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:45:0)
[21:06:23.443] Timer tick, asking Buildbucket for the build status
[21:06:23.559] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:23.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:46:0) after 1m0s
[21:07:23.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:46:0)
[21:07:23.561] Timer tick, asking Buildbucket for the build status
[21:07:23.622] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:23.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:47:0) after 1m0s
[21:08:23.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:47:0)
[21:08:23.696] Timer tick, asking Buildbucket for the build status
[21:08:23.786] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:23.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:48:0) after 1m0s
[21:09:23.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:48:0)
[21:09:23.807] Timer tick, asking Buildbucket for the build status
[21:09:23.904] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:23.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:49:0) after 1m0s
[21:10:23.944] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:49:0)
[21:10:23.944] Timer tick, asking Buildbucket for the build status
[21:10:23.987] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:23.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:50:0) after 1m0s
[21:11:24.025] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:50:0)
[21:11:24.025] Timer tick, asking Buildbucket for the build status
[21:11:24.188] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:24.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:51:0) after 1m0s
[21:12:24.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:51:0)
[21:12:24.208] Timer tick, asking Buildbucket for the build status
[21:12:24.270] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:24.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:52:0) after 1m0s
[21:13:24.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:52:0)
[21:13:24.312] Timer tick, asking Buildbucket for the build status
[21:13:24.494] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:24.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:53:0) after 1m0s
[21:14:24.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:53:0)
[21:14:24.529] Timer tick, asking Buildbucket for the build status
[21:14:24.595] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:24.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:54:0) after 1m0s
[21:15:24.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:54:0)
[21:15:24.590] Timer tick, asking Buildbucket for the build status
[21:15:24.678] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:24.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:55:0) after 1m0s
[21:16:24.653] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:55:0)
[21:16:24.653] Timer tick, asking Buildbucket for the build status
[21:16:24.702] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:24.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:56:0) after 1m0s
[21:17:24.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:56:0)
[21:17:24.728] Timer tick, asking Buildbucket for the build status
[21:17:24.880] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:24.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:57:0) after 1m0s
[21:18:24.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:57:0)
[21:18:24.907] Timer tick, asking Buildbucket for the build status
[21:18:24.979] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:24.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:58:0) after 1m0s
[21:19:24.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:58:0)
[21:19:24.999] Timer tick, asking Buildbucket for the build status
[21:19:25.102] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:25.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:59:0) after 1m0s
[21:20:25.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:59:0)
[21:20:25.114] Timer tick, asking Buildbucket for the build status
[21:20:25.181] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:25.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:60:0) after 1m0s
[21:21:25.235] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:60:0)
[21:21:25.235] Timer tick, asking Buildbucket for the build status
[21:21:25.308] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:25.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:61:0) after 1m0s
[21:22:25.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:61:0)
[21:22:25.292] Timer tick, asking Buildbucket for the build status
[21:22:25.359] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:25.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:62:0) after 1m0s
[21:23:25.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:62:0)
[21:23:25.380] Timer tick, asking Buildbucket for the build status
[21:23:25.613] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:25.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:63:0) after 1m0s
[21:24:25.635] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:63:0)
[21:24:25.635] Timer tick, asking Buildbucket for the build status
[21:24:25.743] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:25.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:64:0) after 1m0s
[21:25:25.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:64:0)
[21:25:25.765] Timer tick, asking Buildbucket for the build status
[21:25:25.856] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:25.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:65:0) after 1m0s
[21:26:25.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:65:0)
[21:26:25.876] Timer tick, asking Buildbucket for the build status
[21:26:26.032] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:26.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:66:0) after 1m0s
[21:27:26.054] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:66:0)
[21:27:26.055] Timer tick, asking Buildbucket for the build status
[21:27:26.211] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:26.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:67:0) after 1m0s
[21:28:26.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:67:0)
[21:28:26.243] Timer tick, asking Buildbucket for the build status
[21:28:26.348] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:26.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:68:0) after 1m0s
[21:29:26.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:68:0)
[21:29:26.359] Timer tick, asking Buildbucket for the build status
[21:29:26.526] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:26.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:69:0) after 1m0s
[21:30:26.549] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:69:0)
[21:30:26.549] Timer tick, asking Buildbucket for the build status
[21:30:26.638] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:26.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:70:0) after 1m0s
[21:31:26.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:70:0)
[21:31:26.609] Timer tick, asking Buildbucket for the build status
[21:31:26.765] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:26.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:71:0) after 1m0s
[21:32:26.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:71:0)
[21:32:26.786] Timer tick, asking Buildbucket for the build status
[21:32:26.857] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:26.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:72:0) after 1m0s
[21:33:26.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:72:0)
[21:33:26.875] Timer tick, asking Buildbucket for the build status
[21:33:27.037] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:27.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:73:0) after 1m0s
[21:34:27.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:73:0)
[21:34:27.068] Timer tick, asking Buildbucket for the build status
[21:34:27.150] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:27.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:74:0) after 1m0s
[21:35:27.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:74:0)
[21:35:27.191] Timer tick, asking Buildbucket for the build status
[21:35:27.322] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:27.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:75:0) after 1m0s
[21:36:27.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:75:0)
[21:36:27.325] Timer tick, asking Buildbucket for the build status
[21:36:27.422] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:27.422] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:76:0) after 1m0s
[21:37:27.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:76:0)
[21:37:27.428] Timer tick, asking Buildbucket for the build status
[21:37:27.622] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:27.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:77:0) after 1m0s
[21:38:27.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:77:0)
[21:38:27.610] Timer tick, asking Buildbucket for the build status
[21:38:27.740] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:27.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:78:0) after 1m0s
[21:39:27.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:78:0)
[21:39:27.761] Timer tick, asking Buildbucket for the build status
[21:39:27.856] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:27.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:79:0) after 1m0s
[21:40:27.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:79:0)
[21:40:27.880] Timer tick, asking Buildbucket for the build status
[21:40:27.964] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:27.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:80:0) after 1m0s
[21:41:27.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:80:0)
[21:41:27.938] Timer tick, asking Buildbucket for the build status
[21:41:28.043] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:28.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:81:0) after 1m0s
[21:42:28.070] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:81:0)
[21:42:28.070] Timer tick, asking Buildbucket for the build status
[21:42:28.514] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:28.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:82:0) after 1m0s
[21:43:28.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:82:0)
[21:43:28.598] Timer tick, asking Buildbucket for the build status
[21:43:28.910] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:28.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:83:0) after 1m0s
[21:44:28.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:83:0)
[21:44:28.950] Timer tick, asking Buildbucket for the build status
[21:44:29.195] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:29.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:84:0) after 1m0s
[21:45:29.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:84:0)
[21:45:29.199] Timer tick, asking Buildbucket for the build status
[21:45:29.388] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:29.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:85:0) after 1m0s
[21:46:29.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:85:0)
[21:46:29.428] Timer tick, asking Buildbucket for the build status
[21:46:29.578] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:29.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:86:0) after 1m0s
[21:47:29.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:86:0)
[21:47:29.600] Timer tick, asking Buildbucket for the build status
[21:47:29.673] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:29.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:87:0) after 1m0s
[21:48:29.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:87:0)
[21:48:29.699] Timer tick, asking Buildbucket for the build status
[21:48:29.761] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:29.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:88:0) after 1m0s
[21:49:29.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:88:0)
[21:49:29.760] Timer tick, asking Buildbucket for the build status
[21:49:29.859] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:29.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:89:0) after 1m0s
[21:50:29.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:89:0)
[21:50:29.882] Timer tick, asking Buildbucket for the build status
[21:50:29.957] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:29.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:90:0) after 1m0s
[21:51:29.962] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:90:0)
[21:51:29.962] Timer tick, asking Buildbucket for the build status
[21:51:30.032] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:30.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:91:0) after 1m0s
[21:52:30.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:91:0)
[21:52:30.064] Timer tick, asking Buildbucket for the build status
[21:52:30.148] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:30.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:92:0) after 1m0s
[21:53:30.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:92:0)
[21:53:30.174] Timer tick, asking Buildbucket for the build status
[21:53:30.268] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:30.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:93:0) after 1m0s
[21:54:30.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:93:0)
[21:54:30.265] Timer tick, asking Buildbucket for the build status
[21:54:30.514] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:30.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:94:0) after 1m0s
[21:55:30.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:94:0)
[21:55:30.536] Timer tick, asking Buildbucket for the build status
[21:55:30.594] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:30.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:95:0) after 1m0s
[21:56:30.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:95:0)
[21:56:30.590] Timer tick, asking Buildbucket for the build status
[21:56:30.677] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:30.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:96:0) after 1m0s
[21:57:30.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:96:0)
[21:57:30.698] Timer tick, asking Buildbucket for the build status
[21:57:30.739] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:30.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:97:0) after 1m0s
[21:58:30.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:97:0)
[21:58:30.764] Timer tick, asking Buildbucket for the build status
[21:58:30.811] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:30.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:98:0) after 1m0s
[21:59:30.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:98:0)
[21:59:30.818] Timer tick, asking Buildbucket for the build status
[21:59:30.943] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:30.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:99:0) after 1m0s
[22:00:30.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:99:0)
[22:00:30.967] Timer tick, asking Buildbucket for the build status
[22:00:31.101] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:31.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:100:0) after 1m0s
[22:01:31.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:100:0)
[22:01:31.123] Timer tick, asking Buildbucket for the build status
[22:01:31.208] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:31.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:101:0) after 1m0s
[22:02:31.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:101:0)
[22:02:31.214] Timer tick, asking Buildbucket for the build status
[22:02:31.375] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:31.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:102:0) after 1m0s
[22:03:31.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:102:0)
[22:03:31.397] Timer tick, asking Buildbucket for the build status
[22:03:31.490] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:31.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:103:0) after 1m0s
[22:04:31.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:103:0)
[22:04:31.511] Timer tick, asking Buildbucket for the build status
[22:04:31.605] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:31.605] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:104:0) after 1m0s
[22:05:31.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:104:0)
[22:05:31.593] Timer tick, asking Buildbucket for the build status
[22:05:31.658] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:31.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:105:0) after 1m0s
[22:06:31.680] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:105:0)
[22:06:31.680] Timer tick, asking Buildbucket for the build status
[22:06:31.734] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:31.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:106:0) after 1m0s
[22:07:31.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:106:0)
[22:07:31.754] Timer tick, asking Buildbucket for the build status
[22:07:31.838] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:31.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:107:0) after 1m0s
[22:08:31.861] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:107:0)
[22:08:31.861] Timer tick, asking Buildbucket for the build status
[22:08:31.908] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:31.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:108:0) after 1m0s
[22:09:31.932] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:108:0)
[22:09:31.932] Timer tick, asking Buildbucket for the build status
[22:09:32.317] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:32.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:109:0) after 1m0s
[22:10:32.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:109:0)
[22:10:32.302] Timer tick, asking Buildbucket for the build status
[22:10:32.487] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:32.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:110:0) after 1m0s
[22:11:32.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:110:0)
[22:11:32.499] Timer tick, asking Buildbucket for the build status
[22:11:32.576] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:32.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:111:0) after 1m0s
[22:12:32.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:111:0)
[22:12:32.556] Timer tick, asking Buildbucket for the build status
[22:12:32.751] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:32.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:112:0) after 1m0s
[22:13:32.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:112:0)
[22:13:32.768] Timer tick, asking Buildbucket for the build status
[22:13:32.939] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:32.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:113:0) after 1m0s
[22:14:32.961] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:113:0)
[22:14:32.961] Timer tick, asking Buildbucket for the build status
[22:14:33.279] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:33.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:114:0) after 1m0s
[22:15:33.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:114:0)
[22:15:33.330] Timer tick, asking Buildbucket for the build status
[22:15:33.389] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:33.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:115:0) after 1m0s
[22:16:33.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:115:0)
[22:16:33.412] Timer tick, asking Buildbucket for the build status
[22:16:33.568] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:33.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:116:0) after 1m0s
[22:17:33.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:116:0)
[22:17:33.594] Timer tick, asking Buildbucket for the build status
[22:17:33.649] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:33.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:117:0) after 1m0s
[22:18:33.652] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:117:0)
[22:18:33.652] Timer tick, asking Buildbucket for the build status
[22:18:33.695] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:33.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:118:0) after 1m0s
[22:19:33.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:118:0)
[22:19:33.718] Timer tick, asking Buildbucket for the build status
[22:19:34.034] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:34.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:119:0) after 1m0s
[22:20:34.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:119:0)
[22:20:34.061] Timer tick, asking Buildbucket for the build status
[22:20:34.191] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:34.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:120:0) after 1m0s
[22:21:34.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:120:0)
[22:21:34.203] Timer tick, asking Buildbucket for the build status
[22:21:34.267] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:34.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:121:0) after 1m0s
[22:22:34.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:121:0)
[22:22:34.291] Timer tick, asking Buildbucket for the build status
[22:22:34.334] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:34.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:122:0) after 1m0s
[22:23:34.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:122:0)
[22:23:34.368] Timer tick, asking Buildbucket for the build status
[22:23:34.663] Build 8916352554109645920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:34.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081782616084256240:123:0) after 1m0s
[22:24:03.309] Received PubSub notification, asking Buildbucket for the build status
[22:24:03.374] Build 8916352554109645920: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:24:03.374] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555107842753780",
"created_by": "project:fuchsia",
"created_ts": "1555100595330790",
"failure_reason": "BUILD_FAILURE",
"id": "8916352554109645920",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"65132e2118140200dc57e2df9d8713dd07e72119\"}}",
"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-8bx8\", \"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\": \"65132e2118140200dc57e2df9d8713dd07e72119\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-8bx8\"], \"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\": [\"4253-3d44409\"], \"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": "1555100620054402",
"status": "COMPLETED",
"status_changed_ts": "1555100620429273",
"tags": [
"builder:clang",
"buildset:commit/git/65132e2118140200dc57e2df9d8713dd07e72119",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/65132e2118140200dc57e2df9d8713dd07e72119",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081782616084256240",
"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/8916352554109645920/+/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:442c09835e5e3d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1555107842942599",
"url": "https://ci.chromium.org/b/8916352554109645920",
"utcnow_ts": "1555107843355355"
}
}
[22:24:03.374] Invocation finished in 2h0m49.31169552s with status FAILED