[18:26:20.098] New invocation is queued and will start shortly
[18:26:21.215] Starting the invocation (attempt 1)
[18:26:21.274] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:26:21.274] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:26:21.274] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081608776844348736",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"3c57976447b5dd83b377aace127b896c62479518\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081608776844348736",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/3c57976447b5dd83b377aace127b896c62479518",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3c57976447b5dd83b377aace127b896c62479518",
"gitiles_ref:refs/heads/master"
]
}
[18:26:21.993] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555266381422473",
"id": "8916178714791814384",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"3c57976447b5dd83b377aace127b896c62479518\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555266381881941",
"tags": [
"builder:clang",
"buildset:commit/git/3c57976447b5dd83b377aace127b896c62479518",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3c57976447b5dd83b377aace127b896c62479518",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081608776844348736",
"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/8916178714791814384/+/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": "1555266381882071",
"url": "https://ci.chromium.org/b/8916178714791814384",
"utcnow_ts": "1555266381981500"
}
}
[18:26:21.993] Task URL: https://ci.chromium.org/b/8916178714791814384
[18:26:21.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:2:0) after 1m0s
[18:26:24.274] Received PubSub notification, asking Buildbucket for the build status
[18:26:24.368] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:22.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:2:0)
[18:27:22.027] Timer tick, asking Buildbucket for the build status
[18:27:22.104] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:22.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:4:0) after 1m0s
[18:28:22.081] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:4:0)
[18:28:22.081] Timer tick, asking Buildbucket for the build status
[18:28:22.123] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:22.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:5:0) after 1m0s
[18:29:22.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:5:0)
[18:29:22.139] Timer tick, asking Buildbucket for the build status
[18:29:22.179] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:22.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:6:0) after 1m0s
[18:30:22.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:6:0)
[18:30:22.196] Timer tick, asking Buildbucket for the build status
[18:30:22.324] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:22.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:7:0) after 1m0s
[18:31:22.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:7:0)
[18:31:22.362] Timer tick, asking Buildbucket for the build status
[18:31:22.433] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:22.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:8:0) after 1m0s
[18:32:22.459] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:8:0)
[18:32:22.459] Timer tick, asking Buildbucket for the build status
[18:32:22.513] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:22.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:9:0) after 1m0s
[18:33:22.575] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:9:0)
[18:33:22.575] Timer tick, asking Buildbucket for the build status
[18:33:22.663] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:22.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:10:0) after 1m0s
[18:34:22.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:10:0)
[18:34:22.679] Timer tick, asking Buildbucket for the build status
[18:34:22.764] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:22.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:11:0) after 1m0s
[18:35:22.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:11:0)
[18:35:22.780] Timer tick, asking Buildbucket for the build status
[18:35:22.861] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:22.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:12:0) after 1m0s
[18:36:22.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:12:0)
[18:36:22.871] Timer tick, asking Buildbucket for the build status
[18:36:22.927] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:22.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:13:0) after 1m0s
[18:37:22.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:13:0)
[18:37:22.948] Timer tick, asking Buildbucket for the build status
[18:37:23.064] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:23.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:14:0) after 1m0s
[18:38:23.056] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:14:0)
[18:38:23.056] Timer tick, asking Buildbucket for the build status
[18:38:23.185] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:23.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:15:0) after 1m0s
[18:39:23.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:15:0)
[18:39:23.204] Timer tick, asking Buildbucket for the build status
[18:39:23.353] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:23.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:16:0) after 1m0s
[18:40:23.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:16:0)
[18:40:23.373] Timer tick, asking Buildbucket for the build status
[18:40:23.429] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:23.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:17:0) after 1m0s
[18:41:23.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:17:0)
[18:41:23.448] Timer tick, asking Buildbucket for the build status
[18:41:23.519] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:23.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:18:0) after 1m0s
[18:42:23.538] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:18:0)
[18:42:23.538] Timer tick, asking Buildbucket for the build status
[18:42:23.658] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:23.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:19:0) after 1m0s
[18:43:23.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:19:0)
[18:43:23.682] Timer tick, asking Buildbucket for the build status
[18:43:23.750] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:23.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:20:0) after 1m0s
[18:44:23.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:20:0)
[18:44:23.791] Timer tick, asking Buildbucket for the build status
[18:44:23.857] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:23.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:21:0) after 1m0s
[18:45:23.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:21:0)
[18:45:23.879] Timer tick, asking Buildbucket for the build status
[18:45:24.469] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:24.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:22:0) after 1m0s
[18:46:24.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:22:0)
[18:46:24.488] Timer tick, asking Buildbucket for the build status
[18:46:24.599] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:24.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:23:0) after 1m0s
[18:47:24.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:23:0)
[18:47:24.598] Timer tick, asking Buildbucket for the build status
[18:47:24.692] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:24.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:24:0) after 1m0s
[18:48:24.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:24:0)
[18:48:24.720] Timer tick, asking Buildbucket for the build status
[18:48:24.808] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:24.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:25:0) after 1m0s
[18:49:24.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:25:0)
[18:49:24.822] Timer tick, asking Buildbucket for the build status
[18:49:24.865] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:24.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:26:0) after 1m0s
[18:50:24.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:26:0)
[18:50:24.880] Timer tick, asking Buildbucket for the build status
[18:50:24.947] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:24.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:27:0) after 1m0s
[18:51:24.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:27:0)
[18:51:24.966] Timer tick, asking Buildbucket for the build status
[18:51:25.064] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:25.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:28:0) after 1m0s
[18:52:25.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:28:0)
[18:52:25.145] Timer tick, asking Buildbucket for the build status
[18:52:25.205] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:25.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:29:0) after 1m0s
[18:53:25.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:29:0)
[18:53:25.222] Timer tick, asking Buildbucket for the build status
[18:53:25.494] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:25.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:30:0) after 1m0s
[18:54:25.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:30:0)
[18:54:25.472] Timer tick, asking Buildbucket for the build status
[18:54:25.529] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:25.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:31:0) after 1m0s
[18:55:25.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:31:0)
[18:55:25.556] Timer tick, asking Buildbucket for the build status
[18:55:25.617] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:25.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:32:0) after 1m0s
[18:56:25.635] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:32:0)
[18:56:25.635] Timer tick, asking Buildbucket for the build status
[18:56:25.766] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:25.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:33:0) after 1m0s
[18:57:25.844] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:33:0)
[18:57:25.844] Timer tick, asking Buildbucket for the build status
[18:57:26.112] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:26.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:34:0) after 1m0s
[18:58:26.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:34:0)
[18:58:26.093] Timer tick, asking Buildbucket for the build status
[18:58:26.213] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:26.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:35:0) after 1m0s
[18:59:26.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:35:0)
[18:59:26.268] Timer tick, asking Buildbucket for the build status
[18:59:26.338] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:26.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:36:0) after 1m0s
[19:00:26.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:36:0)
[19:00:26.362] Timer tick, asking Buildbucket for the build status
[19:00:26.438] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:26.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:37:0) after 1m0s
[19:01:26.456] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:37:0)
[19:01:26.456] Timer tick, asking Buildbucket for the build status
[19:01:26.794] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:26.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:38:0) after 1m0s
[19:02:26.811] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:38:0)
[19:02:26.811] Timer tick, asking Buildbucket for the build status
[19:02:26.851] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:26.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:39:0) after 1m0s
[19:03:26.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:39:0)
[19:03:26.870] Timer tick, asking Buildbucket for the build status
[19:03:27.092] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:27.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:40:0) after 1m0s
[19:04:27.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:40:0)
[19:04:27.104] Timer tick, asking Buildbucket for the build status
[19:04:27.184] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:27.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:41:0) after 1m0s
[19:05:27.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:41:0)
[19:05:27.209] Timer tick, asking Buildbucket for the build status
[19:05:27.456] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:27.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:42:0) after 1m0s
[19:06:27.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:42:0)
[19:06:27.475] Timer tick, asking Buildbucket for the build status
[19:06:27.559] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:27.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:43:0) after 1m0s
[19:07:27.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:43:0)
[19:07:27.582] Timer tick, asking Buildbucket for the build status
[19:07:27.640] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:27.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:44:0) after 1m0s
[19:08:27.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:44:0)
[19:08:27.686] Timer tick, asking Buildbucket for the build status
[19:08:27.755] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:27.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:45:0) after 1m0s
[19:09:27.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:45:0)
[19:09:27.773] Timer tick, asking Buildbucket for the build status
[19:09:27.832] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:27.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:46:0) after 1m0s
[19:10:27.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:46:0)
[19:10:27.872] Timer tick, asking Buildbucket for the build status
[19:10:27.923] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:27.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:47:0) after 1m0s
[19:11:27.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:47:0)
[19:11:27.955] Timer tick, asking Buildbucket for the build status
[19:11:28.104] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:28.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:48:0) after 1m0s
[19:12:28.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:48:0)
[19:12:28.121] Timer tick, asking Buildbucket for the build status
[19:12:28.186] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:28.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:49:0) after 1m0s
[19:13:28.232] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:49:0)
[19:13:28.232] Timer tick, asking Buildbucket for the build status
[19:13:28.280] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:28.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:50:0) after 1m0s
[19:14:28.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:50:0)
[19:14:28.280] Timer tick, asking Buildbucket for the build status
[19:14:28.489] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:28.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:51:0) after 1m0s
[19:15:28.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:51:0)
[19:15:28.515] Timer tick, asking Buildbucket for the build status
[19:15:28.587] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:28.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:52:0) after 1m0s
[19:16:28.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:52:0)
[19:16:28.625] Timer tick, asking Buildbucket for the build status
[19:16:28.760] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:28.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:53:0) after 1m0s
[19:17:28.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:53:0)
[19:17:28.784] Timer tick, asking Buildbucket for the build status
[19:17:28.867] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:28.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:54:0) after 1m0s
[19:18:28.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:54:0)
[19:18:28.890] Timer tick, asking Buildbucket for the build status
[19:18:28.965] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:28.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:55:0) after 1m0s
[19:19:28.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:55:0)
[19:19:28.988] Timer tick, asking Buildbucket for the build status
[19:19:29.054] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:29.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:56:0) after 1m0s
[19:20:29.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:56:0)
[19:20:29.072] Timer tick, asking Buildbucket for the build status
[19:20:29.111] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:29.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:57:0) after 1m0s
[19:21:29.176] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:57:0)
[19:21:29.176] Timer tick, asking Buildbucket for the build status
[19:21:29.250] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:29.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:58:0) after 1m0s
[19:22:29.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:58:0)
[19:22:29.282] Timer tick, asking Buildbucket for the build status
[19:22:29.370] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:29.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:59:0) after 1m0s
[19:23:29.365] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:59:0)
[19:23:29.365] Timer tick, asking Buildbucket for the build status
[19:23:29.534] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:29.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:60:0) after 1m0s
[19:24:29.554] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:60:0)
[19:24:29.554] Timer tick, asking Buildbucket for the build status
[19:24:29.607] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:29.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:61:0) after 1m0s
[19:25:29.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:61:0)
[19:25:29.612] Timer tick, asking Buildbucket for the build status
[19:25:29.684] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:29.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:62:0) after 1m0s
[19:26:29.702] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:62:0)
[19:26:29.702] Timer tick, asking Buildbucket for the build status
[19:26:29.769] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:29.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:63:0) after 1m0s
[19:27:29.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:63:0)
[19:27:29.743] Timer tick, asking Buildbucket for the build status
[19:27:29.991] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:29.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:64:0) after 1m0s
[19:28:29.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:64:0)
[19:28:29.959] Timer tick, asking Buildbucket for the build status
[19:28:30.136] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:30.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:65:0) after 1m0s
[19:29:30.184] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:65:0)
[19:29:30.184] Timer tick, asking Buildbucket for the build status
[19:29:30.479] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:30.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:66:0) after 1m0s
[19:30:30.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:66:0)
[19:30:30.482] Timer tick, asking Buildbucket for the build status
[19:30:30.620] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:30.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:67:0) after 1m0s
[19:31:30.645] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:67:0)
[19:31:30.645] Timer tick, asking Buildbucket for the build status
[19:31:30.696] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:30.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:68:0) after 1m0s
[19:32:30.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:68:0)
[19:32:30.731] Timer tick, asking Buildbucket for the build status
[19:32:30.806] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:30.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:69:0) after 1m0s
[19:33:30.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:69:0)
[19:33:30.829] Timer tick, asking Buildbucket for the build status
[19:33:30.957] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:30.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:70:0) after 1m0s
[19:34:30.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:70:0)
[19:34:30.979] Timer tick, asking Buildbucket for the build status
[19:34:31.059] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:31.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:71:0) after 1m0s
[19:35:31.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:71:0)
[19:35:31.045] Timer tick, asking Buildbucket for the build status
[19:35:31.101] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:31.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:72:0) after 1m0s
[19:36:31.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:72:0)
[19:36:31.122] Timer tick, asking Buildbucket for the build status
[19:36:31.356] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:31.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:73:0) after 1m0s
[19:37:31.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:73:0)
[19:37:31.391] Timer tick, asking Buildbucket for the build status
[19:37:31.604] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:31.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:74:0) after 1m0s
[19:38:31.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:74:0)
[19:38:31.625] Timer tick, asking Buildbucket for the build status
[19:38:31.680] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:31.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:75:0) after 1m0s
[19:39:31.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:75:0)
[19:39:31.709] Timer tick, asking Buildbucket for the build status
[19:39:31.867] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:31.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:76:0) after 1m0s
[19:40:31.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:76:0)
[19:40:31.889] Timer tick, asking Buildbucket for the build status
[19:40:32.048] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:32.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:77:0) after 1m0s
[19:41:32.025] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:77:0)
[19:41:32.025] Timer tick, asking Buildbucket for the build status
[19:41:32.153] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:32.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:78:0) after 1m0s
[19:42:32.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:78:0)
[19:42:32.174] Timer tick, asking Buildbucket for the build status
[19:42:32.232] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:32.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:79:0) after 1m0s
[19:43:32.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:79:0)
[19:43:32.251] Timer tick, asking Buildbucket for the build status
[19:43:32.314] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:32.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:80:0) after 1m0s
[19:44:32.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:80:0)
[19:44:32.361] Timer tick, asking Buildbucket for the build status
[19:44:32.744] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:32.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:81:0) after 1m0s
[19:45:32.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:81:0)
[19:45:32.779] Timer tick, asking Buildbucket for the build status
[19:45:32.830] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:32.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:82:0) after 1m0s
[19:46:32.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:82:0)
[19:46:32.848] Timer tick, asking Buildbucket for the build status
[19:46:32.922] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:32.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:83:0) after 1m0s
[19:47:32.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:83:0)
[19:47:32.955] Timer tick, asking Buildbucket for the build status
[19:47:33.199] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:33.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:84:0) after 1m0s
[19:48:33.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:84:0)
[19:48:33.225] Timer tick, asking Buildbucket for the build status
[19:48:33.274] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:33.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:85:0) after 1m0s
[19:49:33.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:85:0)
[19:49:33.264] Timer tick, asking Buildbucket for the build status
[19:49:33.368] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:33.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:86:0) after 1m0s
[19:50:33.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:86:0)
[19:50:33.392] Timer tick, asking Buildbucket for the build status
[19:50:33.466] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:33.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:87:0) after 1m0s
[19:51:33.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:87:0)
[19:51:33.488] Timer tick, asking Buildbucket for the build status
[19:51:33.559] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:33.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:88:0) after 1m0s
[19:52:33.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:88:0)
[19:52:33.579] Timer tick, asking Buildbucket for the build status
[19:52:33.706] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:33.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:89:0) after 1m0s
[19:53:33.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:89:0)
[19:53:33.736] Timer tick, asking Buildbucket for the build status
[19:53:33.782] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:33.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:90:0) after 1m0s
[19:54:33.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:90:0)
[19:54:33.802] Timer tick, asking Buildbucket for the build status
[19:54:33.912] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:33.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:91:0) after 1m0s
[19:55:33.932] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:91:0)
[19:55:33.932] Timer tick, asking Buildbucket for the build status
[19:55:34.119] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:34.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:92:0) after 1m0s
[19:56:34.100] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:92:0)
[19:56:34.100] Timer tick, asking Buildbucket for the build status
[19:56:34.159] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:34.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:93:0) after 1m0s
[19:57:34.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:93:0)
[19:57:34.148] Timer tick, asking Buildbucket for the build status
[19:57:34.364] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:34.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:94:0) after 1m0s
[19:58:34.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:94:0)
[19:58:34.389] Timer tick, asking Buildbucket for the build status
[19:58:34.452] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:34.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:95:0) after 1m0s
[19:59:34.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:95:0)
[19:59:34.492] Timer tick, asking Buildbucket for the build status
[19:59:34.597] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:34.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:96:0) after 1m0s
[20:00:34.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:96:0)
[20:00:34.619] Timer tick, asking Buildbucket for the build status
[20:00:34.751] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:34.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:97:0) after 1m0s
[20:01:34.750] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:97:0)
[20:01:34.750] Timer tick, asking Buildbucket for the build status
[20:01:34.832] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:34.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:98:0) after 1m0s
[20:02:34.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:98:0)
[20:02:34.852] Timer tick, asking Buildbucket for the build status
[20:02:34.902] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:34.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:99:0) after 1m0s
[20:03:34.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:99:0)
[20:03:34.929] Timer tick, asking Buildbucket for the build status
[20:03:35.114] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:35.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:100:0) after 1m0s
[20:04:35.140] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:100:0)
[20:04:35.140] Timer tick, asking Buildbucket for the build status
[20:04:35.289] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:35.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:101:0) after 1m0s
[20:05:35.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:101:0)
[20:05:35.319] Timer tick, asking Buildbucket for the build status
[20:05:35.570] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:35.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:102:0) after 1m0s
[20:06:35.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:102:0)
[20:06:35.596] Timer tick, asking Buildbucket for the build status
[20:06:35.770] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:35.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:103:0) after 1m0s
[20:07:35.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:103:0)
[20:07:35.794] Timer tick, asking Buildbucket for the build status
[20:07:35.868] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:35.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:104:0) after 1m0s
[20:08:35.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:104:0)
[20:08:35.896] Timer tick, asking Buildbucket for the build status
[20:08:36.004] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:36.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:105:0) after 1m0s
[20:09:36.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:105:0)
[20:09:36.105] Timer tick, asking Buildbucket for the build status
[20:09:36.189] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:36.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:106:0) after 1m0s
[20:10:36.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:106:0)
[20:10:36.216] Timer tick, asking Buildbucket for the build status
[20:10:36.308] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:36.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:107:0) after 1m0s
[20:11:36.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:107:0)
[20:11:36.372] Timer tick, asking Buildbucket for the build status
[20:11:36.621] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:36.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:108:0) after 1m0s
[20:12:36.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:108:0)
[20:12:36.641] Timer tick, asking Buildbucket for the build status
[20:12:36.686] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:36.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:109:0) after 1m0s
[20:13:36.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:109:0)
[20:13:36.726] Timer tick, asking Buildbucket for the build status
[20:13:36.820] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:36.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:110:0) after 1m0s
[20:14:36.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:110:0)
[20:14:36.804] Timer tick, asking Buildbucket for the build status
[20:14:36.975] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:36.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:111:0) after 1m0s
[20:15:37.003] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:111:0)
[20:15:37.003] Timer tick, asking Buildbucket for the build status
[20:15:37.168] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:37.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:112:0) after 1m0s
[20:16:37.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:112:0)
[20:16:37.159] Timer tick, asking Buildbucket for the build status
[20:16:37.222] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:37.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:113:0) after 1m0s
[20:17:37.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:113:0)
[20:17:37.243] Timer tick, asking Buildbucket for the build status
[20:17:37.353] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:37.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:114:0) after 1m0s
[20:18:37.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:114:0)
[20:18:37.351] Timer tick, asking Buildbucket for the build status
[20:18:37.397] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:37.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:115:0) after 1m0s
[20:19:37.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:115:0)
[20:19:37.388] Timer tick, asking Buildbucket for the build status
[20:19:37.559] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:37.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:116:0) after 1m0s
[20:20:37.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:116:0)
[20:20:37.598] Timer tick, asking Buildbucket for the build status
[20:20:37.683] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:37.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:117:0) after 1m0s
[20:21:37.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:117:0)
[20:21:37.703] Timer tick, asking Buildbucket for the build status
[20:21:37.989] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:37.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:118:0) after 1m0s
[20:22:38.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:118:0)
[20:22:38.013] Timer tick, asking Buildbucket for the build status
[20:22:38.591] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:38.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:119:0) after 1m0s
[20:23:38.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:119:0)
[20:23:38.583] Timer tick, asking Buildbucket for the build status
[20:23:38.809] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:38.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:120:0) after 1m0s
[20:24:38.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:120:0)
[20:24:38.802] Timer tick, asking Buildbucket for the build status
[20:24:38.881] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:38.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:121:0) after 1m0s
[20:25:38.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:121:0)
[20:25:38.886] Timer tick, asking Buildbucket for the build status
[20:25:39.035] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:39.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:122:0) after 1m0s
[20:26:39.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:122:0)
[20:26:39.067] Timer tick, asking Buildbucket for the build status
[20:26:39.137] Build 8916178714791814384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:39.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081608776844348736:123:0) after 1m0s
[20:26:45.538] Received PubSub notification, asking Buildbucket for the build status
[20:26:45.644] Build 8916178714791814384: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:26:45.644] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555273605213498",
"created_by": "project:fuchsia",
"created_ts": "1555266381422473",
"failure_reason": "BUILD_FAILURE",
"id": "8916178714791814384",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"3c57976447b5dd83b377aace127b896c62479518\"}}",
"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-4kw8\", \"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\": \"3c57976447b5dd83b377aace127b896c62479518\", \"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-4kw8\"], \"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": "1555266383706048",
"status": "COMPLETED",
"status_changed_ts": "1555266384012861",
"tags": [
"builder:clang",
"buildset:commit/git/3c57976447b5dd83b377aace127b896c62479518",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3c57976447b5dd83b377aace127b896c62479518",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081608776844348736",
"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/8916178714791814384/+/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:4435eb315638e610",
"user_agent:luci-scheduler"
],
"updated_ts": "1555273605264704",
"url": "https://ci.chromium.org/b/8916178714791814384",
"utcnow_ts": "1555273605635177"
}
}
[20:26:45.644] Invocation finished in 2h0m25.56309625s with status FAILED