[10:53:16.357] New invocation is queued and will start shortly
[10:53:17.439] Starting the invocation (attempt 1)
[10:53:17.474] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:53:17.474] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:53:17.474] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079372356902547200",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"eba9a6e08fcbd16c8f52b9977475e349073bcf8c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079372356902547200",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/eba9a6e08fcbd16c8f52b9977475e349073bcf8c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/eba9a6e08fcbd16c8f52b9977475e349073bcf8c",
"gitiles_ref:refs/heads/master"
]
}
[10:53:18.675] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557399197606138",
"id": "8913942294929137312",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"eba9a6e08fcbd16c8f52b9977475e349073bcf8c\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557399198393682",
"tags": [
"builder:clang",
"buildset:commit/git/eba9a6e08fcbd16c8f52b9977475e349073bcf8c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/eba9a6e08fcbd16c8f52b9977475e349073bcf8c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079372356902547200",
"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/8913942294929137312/+/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": "1557399198393799",
"url": "https://ci.chromium.org/b/8913942294929137312",
"utcnow_ts": "1557399198667103"
}
}
[10:53:18.675] Task URL: https://ci.chromium.org/b/8913942294929137312
[10:53:18.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:2:0) after 1m0s
[10:53:30.466] Received PubSub notification, asking Buildbucket for the build status
[10:53:30.517] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:18.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:2:0)
[10:54:18.693] Timer tick, asking Buildbucket for the build status
[10:54:18.813] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:18.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:4:0) after 1m0s
[10:55:19.723] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:4:0)
[10:55:19.723] Timer tick, asking Buildbucket for the build status
[10:55:19.970] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:19.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:5:0) after 1m0s
[10:56:19.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:5:0)
[10:56:19.992] Timer tick, asking Buildbucket for the build status
[10:56:20.182] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:20.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:6:0) after 1m0s
[10:57:20.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:6:0)
[10:57:20.186] Timer tick, asking Buildbucket for the build status
[10:57:20.259] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:20.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:7:0) after 1m0s
[10:58:20.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:7:0)
[10:58:20.280] Timer tick, asking Buildbucket for the build status
[10:58:20.336] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:20.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:8:0) after 1m0s
[10:59:20.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:8:0)
[10:59:20.369] Timer tick, asking Buildbucket for the build status
[10:59:20.578] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:20.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:9:0) after 1m0s
[11:00:20.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:9:0)
[11:00:20.576] Timer tick, asking Buildbucket for the build status
[11:00:20.616] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:20.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:10:0) after 1m0s
[11:01:20.643] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:10:0)
[11:01:20.643] Timer tick, asking Buildbucket for the build status
[11:01:20.715] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:20.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:11:0) after 1m0s
[11:02:20.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:11:0)
[11:02:20.742] Timer tick, asking Buildbucket for the build status
[11:02:20.866] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:20.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:12:0) after 1m0s
[11:03:20.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:12:0)
[11:03:20.892] Timer tick, asking Buildbucket for the build status
[11:03:21.057] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:21.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:13:0) after 1m0s
[11:04:21.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:13:0)
[11:04:21.094] Timer tick, asking Buildbucket for the build status
[11:04:21.173] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:21.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:14:0) after 1m0s
[11:05:21.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:14:0)
[11:05:21.319] Timer tick, asking Buildbucket for the build status
[11:05:21.399] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:21.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:15:0) after 1m0s
[11:06:21.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:15:0)
[11:06:21.401] Timer tick, asking Buildbucket for the build status
[11:06:21.652] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:21.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:16:0) after 1m0s
[11:07:21.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:16:0)
[11:07:21.654] Timer tick, asking Buildbucket for the build status
[11:07:21.705] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:21.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:17:0) after 1m0s
[11:08:21.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:17:0)
[11:08:21.728] Timer tick, asking Buildbucket for the build status
[11:08:21.807] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:21.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:18:0) after 1m0s
[11:09:21.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:18:0)
[11:09:21.804] Timer tick, asking Buildbucket for the build status
[11:09:21.851] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:21.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:19:0) after 1m0s
[11:10:21.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:19:0)
[11:10:21.877] Timer tick, asking Buildbucket for the build status
[11:10:22.560] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:22.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:20:0) after 1m0s
[11:11:22.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:20:0)
[11:11:22.588] Timer tick, asking Buildbucket for the build status
[11:11:22.695] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:22.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:21:0) after 1m0s
[11:12:22.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:21:0)
[11:12:22.682] Timer tick, asking Buildbucket for the build status
[11:12:22.889] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:22.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:22:0) after 1m0s
[11:13:22.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:22:0)
[11:13:22.907] Timer tick, asking Buildbucket for the build status
[11:13:23.080] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:23.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:23:0) after 1m0s
[11:14:23.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:23:0)
[11:14:23.072] Timer tick, asking Buildbucket for the build status
[11:14:23.192] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:23.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:24:0) after 1m0s
[11:15:23.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:24:0)
[11:15:23.215] Timer tick, asking Buildbucket for the build status
[11:15:23.305] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:23.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:25:0) after 1m0s
[11:16:23.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:25:0)
[11:16:23.330] Timer tick, asking Buildbucket for the build status
[11:16:23.410] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:23.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:26:0) after 1m0s
[11:17:23.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:26:0)
[11:17:23.436] Timer tick, asking Buildbucket for the build status
[11:17:23.477] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:23.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:27:0) after 1m0s
[11:18:23.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:27:0)
[11:18:23.549] Timer tick, asking Buildbucket for the build status
[11:18:23.786] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:23.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:28:0) after 1m0s
[11:19:23.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:28:0)
[11:19:23.806] Timer tick, asking Buildbucket for the build status
[11:19:23.976] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:23.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:29:0) after 1m0s
[11:20:23.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:29:0)
[11:20:23.997] Timer tick, asking Buildbucket for the build status
[11:20:24.088] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:24.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:30:0) after 1m0s
[11:21:24.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:30:0)
[11:21:24.109] Timer tick, asking Buildbucket for the build status
[11:21:24.166] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:24.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:31:0) after 1m0s
[11:22:24.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:31:0)
[11:22:24.192] Timer tick, asking Buildbucket for the build status
[11:22:24.338] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:24.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:32:0) after 1m0s
[11:23:24.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:32:0)
[11:23:24.372] Timer tick, asking Buildbucket for the build status
[11:23:24.503] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:24.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:33:0) after 1m0s
[11:24:24.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:33:0)
[11:24:24.522] Timer tick, asking Buildbucket for the build status
[11:24:24.560] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:24.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:34:0) after 1m0s
[11:25:24.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:34:0)
[11:25:24.579] Timer tick, asking Buildbucket for the build status
[11:25:24.746] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:24.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:35:0) after 1m0s
[11:26:24.748] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:35:0)
[11:26:24.748] Timer tick, asking Buildbucket for the build status
[11:26:24.795] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:24.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:36:0) after 1m0s
[11:27:24.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:36:0)
[11:27:24.832] Timer tick, asking Buildbucket for the build status
[11:27:25.080] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:25.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:37:0) after 1m0s
[11:28:25.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:37:0)
[11:28:25.090] Timer tick, asking Buildbucket for the build status
[11:28:25.163] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:25.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:38:0) after 1m0s
[11:29:25.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:38:0)
[11:29:25.277] Timer tick, asking Buildbucket for the build status
[11:29:25.457] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:25.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:39:0) after 1m0s
[11:30:25.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:39:0)
[11:30:25.490] Timer tick, asking Buildbucket for the build status
[11:30:25.584] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:25.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:40:0) after 1m0s
[11:31:25.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:40:0)
[11:31:25.599] Timer tick, asking Buildbucket for the build status
[11:31:26.294] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:26.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:41:0) after 1m0s
[11:32:26.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:41:0)
[11:32:26.305] Timer tick, asking Buildbucket for the build status
[11:32:26.389] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:26.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:42:0) after 1m0s
[11:33:26.422] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:42:0)
[11:33:26.422] Timer tick, asking Buildbucket for the build status
[11:33:26.478] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:26.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:43:0) after 1m0s
[11:34:26.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:43:0)
[11:34:26.499] Timer tick, asking Buildbucket for the build status
[11:34:26.692] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:26.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:44:0) after 1m0s
[11:35:26.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:44:0)
[11:35:26.709] Timer tick, asking Buildbucket for the build status
[11:35:26.757] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:26.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:45:0) after 1m0s
[11:36:26.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:45:0)
[11:36:26.780] Timer tick, asking Buildbucket for the build status
[11:36:26.836] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:26.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:46:0) after 1m0s
[11:37:26.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:46:0)
[11:37:26.862] Timer tick, asking Buildbucket for the build status
[11:37:26.928] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:26.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:47:0) after 1m0s
[11:38:26.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:47:0)
[11:38:26.966] Timer tick, asking Buildbucket for the build status
[11:38:27.201] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:27.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:48:0) after 1m0s
[11:39:27.221] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:48:0)
[11:39:27.222] Timer tick, asking Buildbucket for the build status
[11:39:27.260] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:27.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:49:0) after 1m0s
[11:40:27.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:49:0)
[11:40:27.282] Timer tick, asking Buildbucket for the build status
[11:40:27.404] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:27.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:50:0) after 1m0s
[11:41:27.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:50:0)
[11:41:27.428] Timer tick, asking Buildbucket for the build status
[11:41:27.722] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:27.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:51:0) after 1m0s
[11:42:27.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:51:0)
[11:42:27.745] Timer tick, asking Buildbucket for the build status
[11:42:27.898] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:27.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:52:0) after 1m0s
[11:43:27.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:52:0)
[11:43:27.878] Timer tick, asking Buildbucket for the build status
[11:43:27.941] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:27.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:53:0) after 1m0s
[11:44:27.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:53:0)
[11:44:27.970] Timer tick, asking Buildbucket for the build status
[11:44:28.018] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:28.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:54:0) after 1m0s
[11:45:28.066] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:54:0)
[11:45:28.066] Timer tick, asking Buildbucket for the build status
[11:45:28.325] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:28.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:55:0) after 1m0s
[11:46:28.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:55:0)
[11:46:28.331] Timer tick, asking Buildbucket for the build status
[11:46:28.404] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:28.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:56:0) after 1m0s
[11:47:28.437] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:56:0)
[11:47:28.437] Timer tick, asking Buildbucket for the build status
[11:47:28.502] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:28.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:57:0) after 1m0s
[11:48:28.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:57:0)
[11:48:28.526] Timer tick, asking Buildbucket for the build status
[11:48:28.609] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:28.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:58:0) after 1m0s
[11:49:28.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:58:0)
[11:49:28.630] Timer tick, asking Buildbucket for the build status
[11:49:28.983] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:28.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:59:0) after 1m0s
[11:50:29.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:59:0)
[11:50:29.000] Timer tick, asking Buildbucket for the build status
[11:50:29.063] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:29.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:60:0) after 1m0s
[11:51:29.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:60:0)
[11:51:29.090] Timer tick, asking Buildbucket for the build status
[11:51:29.144] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:29.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:61:0) after 1m0s
[11:52:29.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:61:0)
[11:52:29.163] Timer tick, asking Buildbucket for the build status
[11:52:29.262] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:29.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:62:0) after 1m0s
[11:53:29.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:62:0)
[11:53:29.281] Timer tick, asking Buildbucket for the build status
[11:53:29.355] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:29.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:63:0) after 1m0s
[11:54:29.376] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:63:0)
[11:54:29.376] Timer tick, asking Buildbucket for the build status
[11:54:29.419] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:29.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:64:0) after 1m0s
[11:55:29.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:64:0)
[11:55:29.397] Timer tick, asking Buildbucket for the build status
[11:55:29.491] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:29.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:65:0) after 1m0s
[11:56:29.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:65:0)
[11:56:29.513] Timer tick, asking Buildbucket for the build status
[11:56:29.794] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:29.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:66:0) after 1m0s
[11:57:29.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:66:0)
[11:57:29.813] Timer tick, asking Buildbucket for the build status
[11:57:29.888] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:29.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:67:0) after 1m0s
[11:58:29.932] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:67:0)
[11:58:29.932] Timer tick, asking Buildbucket for the build status
[11:58:30.070] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:30.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:68:0) after 1m0s
[11:59:30.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:68:0)
[11:59:30.088] Timer tick, asking Buildbucket for the build status
[11:59:30.195] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:30.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:69:0) after 1m0s
[12:00:30.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:69:0)
[12:00:30.213] Timer tick, asking Buildbucket for the build status
[12:00:30.563] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:30.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:70:0) after 1m0s
[12:01:30.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:70:0)
[12:01:30.593] Timer tick, asking Buildbucket for the build status
[12:01:30.659] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:30.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:71:0) after 1m0s
[12:02:30.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:71:0)
[12:02:30.701] Timer tick, asking Buildbucket for the build status
[12:02:30.747] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:30.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:72:0) after 1m0s
[12:03:30.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:72:0)
[12:03:30.770] Timer tick, asking Buildbucket for the build status
[12:03:30.955] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:30.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:73:0) after 1m0s
[12:04:30.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:73:0)
[12:04:30.989] Timer tick, asking Buildbucket for the build status
[12:04:31.063] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:31.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:74:0) after 1m0s
[12:05:31.080] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:74:0)
[12:05:31.080] Timer tick, asking Buildbucket for the build status
[12:05:32.047] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:32.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:75:0) after 1m0s
[12:06:32.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:75:0)
[12:06:32.180] Timer tick, asking Buildbucket for the build status
[12:06:32.349] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:32.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:76:0) after 1m0s
[12:07:32.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:76:0)
[12:07:32.382] Timer tick, asking Buildbucket for the build status
[12:07:32.576] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:32.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:77:0) after 1m0s
[12:08:32.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:77:0)
[12:08:32.615] Timer tick, asking Buildbucket for the build status
[12:08:32.667] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:32.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:78:0) after 1m0s
[12:09:32.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:78:0)
[12:09:32.821] Timer tick, asking Buildbucket for the build status
[12:09:33.210] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:33.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:79:0) after 1m0s
[12:10:33.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:79:0)
[12:10:33.257] Timer tick, asking Buildbucket for the build status
[12:10:33.384] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:33.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:80:0) after 1m0s
[12:11:33.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:80:0)
[12:11:33.406] Timer tick, asking Buildbucket for the build status
[12:11:33.470] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:33.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:81:0) after 1m0s
[12:12:33.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:81:0)
[12:12:33.493] Timer tick, asking Buildbucket for the build status
[12:12:33.551] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:33.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:82:0) after 1m0s
[12:13:33.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:82:0)
[12:13:33.579] Timer tick, asking Buildbucket for the build status
[12:13:33.734] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:33.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:83:0) after 1m0s
[12:14:33.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:83:0)
[12:14:33.753] Timer tick, asking Buildbucket for the build status
[12:14:34.054] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:34.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:84:0) after 1m0s
[12:15:34.078] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:84:0)
[12:15:34.078] Timer tick, asking Buildbucket for the build status
[12:15:34.205] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:34.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:85:0) after 1m0s
[12:16:34.219] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:85:0)
[12:16:34.219] Timer tick, asking Buildbucket for the build status
[12:16:34.337] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:34.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:86:0) after 1m0s
[12:17:34.352] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:86:0)
[12:17:34.352] Timer tick, asking Buildbucket for the build status
[12:17:34.458] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:34.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:87:0) after 1m0s
[12:18:34.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:87:0)
[12:18:34.482] Timer tick, asking Buildbucket for the build status
[12:18:34.625] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:34.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:88:0) after 1m0s
[12:19:34.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:88:0)
[12:19:34.616] Timer tick, asking Buildbucket for the build status
[12:19:34.670] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:34.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:89:0) after 1m0s
[12:20:34.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:89:0)
[12:20:34.699] Timer tick, asking Buildbucket for the build status
[12:20:34.798] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:34.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:90:0) after 1m0s
[12:21:34.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:90:0)
[12:21:34.818] Timer tick, asking Buildbucket for the build status
[12:21:34.857] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:34.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:91:0) after 1m0s
[12:22:34.885] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:91:0)
[12:22:34.885] Timer tick, asking Buildbucket for the build status
[12:22:35.003] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:35.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:92:0) after 1m0s
[12:23:35.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:92:0)
[12:23:35.086] Timer tick, asking Buildbucket for the build status
[12:23:35.134] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:35.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:93:0) after 1m0s
[12:24:35.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:93:0)
[12:24:35.159] Timer tick, asking Buildbucket for the build status
[12:24:35.317] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:35.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:94:0) after 1m0s
[12:25:35.337] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:94:0)
[12:25:35.337] Timer tick, asking Buildbucket for the build status
[12:25:35.381] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:35.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:95:0) after 1m0s
[12:26:35.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:95:0)
[12:26:35.403] Timer tick, asking Buildbucket for the build status
[12:26:35.540] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:35.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:96:0) after 1m0s
[12:27:35.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:96:0)
[12:27:35.540] Timer tick, asking Buildbucket for the build status
[12:27:35.687] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:35.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:97:0) after 1m0s
[12:28:35.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:97:0)
[12:28:35.705] Timer tick, asking Buildbucket for the build status
[12:28:35.791] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:35.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:98:0) after 1m0s
[12:29:35.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:98:0)
[12:29:35.805] Timer tick, asking Buildbucket for the build status
[12:29:35.852] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:35.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:99:0) after 1m0s
[12:30:35.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:99:0)
[12:30:35.875] Timer tick, asking Buildbucket for the build status
[12:30:36.009] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:36.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:100:0) after 1m0s
[12:31:35.991] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:100:0)
[12:31:35.991] Timer tick, asking Buildbucket for the build status
[12:31:36.307] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:36.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:101:0) after 1m0s
[12:32:36.337] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:101:0)
[12:32:36.337] Timer tick, asking Buildbucket for the build status
[12:32:36.858] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:36.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:102:0) after 1m0s
[12:33:36.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:102:0)
[12:33:36.870] Timer tick, asking Buildbucket for the build status
[12:33:36.913] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:36.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:103:0) after 1m0s
[12:34:36.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:103:0)
[12:34:36.920] Timer tick, asking Buildbucket for the build status
[12:34:37.005] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:37.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:104:0) after 1m0s
[12:35:36.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:104:0)
[12:35:36.986] Timer tick, asking Buildbucket for the build status
[12:35:37.122] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:37.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:105:0) after 1m0s
[12:36:37.313] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:105:0)
[12:36:37.313] Timer tick, asking Buildbucket for the build status
[12:36:37.353] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:37.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:106:0) after 1m0s
[12:37:37.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:106:0)
[12:37:37.359] Timer tick, asking Buildbucket for the build status
[12:37:37.409] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:37.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:107:0) after 1m0s
[12:38:37.495] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:107:0)
[12:38:37.495] Timer tick, asking Buildbucket for the build status
[12:38:37.586] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:37.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:108:0) after 1m0s
[12:39:37.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:108:0)
[12:39:37.722] Timer tick, asking Buildbucket for the build status
[12:39:37.767] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:37.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:109:0) after 1m0s
[12:40:38.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:109:0)
[12:40:38.015] Timer tick, asking Buildbucket for the build status
[12:40:38.079] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:38.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:110:0) after 1m0s
[12:41:38.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:110:0)
[12:41:38.131] Timer tick, asking Buildbucket for the build status
[12:41:38.190] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:38.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:111:0) after 1m0s
[12:42:38.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:111:0)
[12:42:38.190] Timer tick, asking Buildbucket for the build status
[12:42:38.256] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:38.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:112:0) after 1m0s
[12:43:38.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:112:0)
[12:43:38.321] Timer tick, asking Buildbucket for the build status
[12:43:38.685] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:38.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:113:0) after 1m0s
[12:44:38.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:113:0)
[12:44:38.707] Timer tick, asking Buildbucket for the build status
[12:44:38.776] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:38.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:114:0) after 1m0s
[12:45:38.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:114:0)
[12:45:38.790] Timer tick, asking Buildbucket for the build status
[12:45:38.885] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:38.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:115:0) after 1m0s
[12:46:38.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:115:0)
[12:46:38.948] Timer tick, asking Buildbucket for the build status
[12:46:38.995] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:38.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:116:0) after 1m0s
[12:47:39.003] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:116:0)
[12:47:39.003] Timer tick, asking Buildbucket for the build status
[12:47:39.056] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:39.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:117:0) after 1m0s
[12:48:39.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:117:0)
[12:48:39.098] Timer tick, asking Buildbucket for the build status
[12:48:39.305] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:39.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:118:0) after 1m0s
[12:49:39.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:118:0)
[12:49:39.325] Timer tick, asking Buildbucket for the build status
[12:49:39.370] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:39.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:119:0) after 1m0s
[12:50:39.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:119:0)
[12:50:39.404] Timer tick, asking Buildbucket for the build status
[12:50:39.459] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:39.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:120:0) after 1m0s
[12:51:39.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:120:0)
[12:51:39.482] Timer tick, asking Buildbucket for the build status
[12:51:39.581] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:39.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:121:0) after 1m0s
[12:52:39.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:121:0)
[12:52:39.671] Timer tick, asking Buildbucket for the build status
[12:52:39.763] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:39.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:122:0) after 1m0s
[12:53:39.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:122:0)
[12:53:39.751] Timer tick, asking Buildbucket for the build status
[12:53:39.795] Build 8913942294929137312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:39.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079372356902547200:123:0) after 1m0s
[12:54:06.818] Received PubSub notification, asking Buildbucket for the build status
[12:54:06.958] Build 8913942294929137312: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[12:54:06.958] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1557406446209895",
"created_by": "project:fuchsia",
"created_ts": "1557399197606138",
"failure_reason": "BUILD_FAILURE",
"id": "8913942294929137312",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"eba9a6e08fcbd16c8f52b9977475e349073bcf8c\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-b-1-qua1\", \"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\": \"eba9a6e08fcbd16c8f52b9977475e349073bcf8c\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-b-1-qua1\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4291-fed3552\"], \"ssd\": [\"1\"], \"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": "1557399207816629",
"status": "COMPLETED",
"status_changed_ts": "1557399210148402",
"tags": [
"builder:clang",
"buildset:commit/git/eba9a6e08fcbd16c8f52b9977475e349073bcf8c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/eba9a6e08fcbd16c8f52b9977475e349073bcf8c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079372356902547200",
"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/8913942294929137312/+/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:44b50b6ccaace610",
"user_agent:luci-scheduler"
],
"updated_ts": "1557406446405992",
"url": "https://ci.chromium.org/b/8913942294929137312",
"utcnow_ts": "1557406446942263"
}
}
[12:54:06.959] Invocation finished in 2h0m50.617900021s with status FAILED