[23:14:06.417] New invocation is queued and will start shortly
[23:14:07.505] Starting the invocation (attempt 1)
[23:14:07.652] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:14:07.652] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:14:07.652] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9082949626290524192",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"a28ee7ec4f07bee0145ff9bb74693009a5be2126\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9082949626290524192",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/a28ee7ec4f07bee0145ff9bb74693009a5be2126",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a28ee7ec4f07bee0145ff9bb74693009a5be2126",
"gitiles_ref:refs/heads/master"
]
}
[23:14:08.331] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553987647808886",
"id": "8917519564170744688",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a28ee7ec4f07bee0145ff9bb74693009a5be2126\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553987648209475",
"tags": [
"builder:clang",
"buildset:commit/git/a28ee7ec4f07bee0145ff9bb74693009a5be2126",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a28ee7ec4f07bee0145ff9bb74693009a5be2126",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082949626290524192",
"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/8917519564170744688/+/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": "1553987648209678",
"url": "https://ci.chromium.org/b/8917519564170744688",
"utcnow_ts": "1553987648314994"
}
}
[23:14:08.331] Task URL: https://ci.chromium.org/b/8917519564170744688
[23:14:08.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:2:0) after 1m0s
[23:14:12.304] Received PubSub notification, asking Buildbucket for the build status
[23:14:12.470] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:08.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:2:0)
[23:15:08.323] Timer tick, asking Buildbucket for the build status
[23:15:08.402] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:08.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:4:0) after 1m0s
[23:16:08.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:4:0)
[23:16:08.432] Timer tick, asking Buildbucket for the build status
[23:16:08.651] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:08.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:5:0) after 1m0s
[23:17:08.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:5:0)
[23:17:08.666] Timer tick, asking Buildbucket for the build status
[23:17:08.779] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:08.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:6:0) after 1m0s
[23:18:08.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:6:0)
[23:18:08.795] Timer tick, asking Buildbucket for the build status
[23:18:08.872] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:08.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:7:0) after 1m0s
[23:19:08.974] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:7:0)
[23:19:08.974] Timer tick, asking Buildbucket for the build status
[23:19:09.016] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:09.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:8:0) after 1m0s
[23:20:09.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:8:0)
[23:20:09.034] Timer tick, asking Buildbucket for the build status
[23:20:09.420] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:09.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:9:0) after 1m0s
[23:21:09.462] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:9:0)
[23:21:09.462] Timer tick, asking Buildbucket for the build status
[23:21:09.520] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:09.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:10:0) after 1m0s
[23:22:09.566] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:10:0)
[23:22:09.566] Timer tick, asking Buildbucket for the build status
[23:22:09.670] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:09.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:11:0) after 1m0s
[23:23:09.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:11:0)
[23:23:09.686] Timer tick, asking Buildbucket for the build status
[23:23:09.753] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:09.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:12:0) after 1m0s
[23:24:09.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:12:0)
[23:24:09.755] Timer tick, asking Buildbucket for the build status
[23:24:09.829] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:09.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:13:0) after 1m0s
[23:25:09.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:13:0)
[23:25:09.850] Timer tick, asking Buildbucket for the build status
[23:25:09.937] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:09.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:14:0) after 1m0s
[23:26:09.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:14:0)
[23:26:09.972] Timer tick, asking Buildbucket for the build status
[23:26:10.079] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:10.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:15:0) after 1m0s
[23:27:10.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:15:0)
[23:27:10.115] Timer tick, asking Buildbucket for the build status
[23:27:10.159] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:10.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:16:0) after 1m0s
[23:28:10.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:16:0)
[23:28:10.177] Timer tick, asking Buildbucket for the build status
[23:28:10.253] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:10.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:17:0) after 1m0s
[23:29:10.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:17:0)
[23:29:10.327] Timer tick, asking Buildbucket for the build status
[23:29:10.784] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:10.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:18:0) after 1m0s
[23:30:10.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:18:0)
[23:30:10.875] Timer tick, asking Buildbucket for the build status
[23:30:10.963] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:10.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:19:0) after 1m0s
[23:31:10.982] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:19:0)
[23:31:10.982] Timer tick, asking Buildbucket for the build status
[23:31:11.156] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:11.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:20:0) after 1m0s
[23:32:11.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:20:0)
[23:32:11.172] Timer tick, asking Buildbucket for the build status
[23:32:11.266] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:11.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:21:0) after 1m0s
[23:33:11.307] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:21:0)
[23:33:11.307] Timer tick, asking Buildbucket for the build status
[23:33:11.475] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:11.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:22:0) after 1m0s
[23:34:11.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:22:0)
[23:34:11.492] Timer tick, asking Buildbucket for the build status
[23:34:11.569] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:11.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:23:0) after 1m0s
[23:35:11.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:23:0)
[23:35:11.628] Timer tick, asking Buildbucket for the build status
[23:35:11.879] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:11.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:24:0) after 1m0s
[23:36:11.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:24:0)
[23:36:11.894] Timer tick, asking Buildbucket for the build status
[23:36:12.056] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:12.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:25:0) after 1m0s
[23:37:12.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:25:0)
[23:37:12.075] Timer tick, asking Buildbucket for the build status
[23:37:12.142] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:12.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:26:0) after 1m0s
[23:38:12.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:26:0)
[23:38:12.177] Timer tick, asking Buildbucket for the build status
[23:38:12.392] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:12.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:27:0) after 1m0s
[23:39:12.510] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:27:0)
[23:39:12.510] Timer tick, asking Buildbucket for the build status
[23:39:12.579] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:12.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:28:0) after 1m0s
[23:40:12.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:28:0)
[23:40:12.599] Timer tick, asking Buildbucket for the build status
[23:40:12.641] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:12.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:29:0) after 1m0s
[23:41:12.659] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:29:0)
[23:41:12.659] Timer tick, asking Buildbucket for the build status
[23:41:12.789] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:12.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:30:0) after 1m0s
[23:42:12.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:30:0)
[23:42:12.809] Timer tick, asking Buildbucket for the build status
[23:42:12.891] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:12.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:31:0) after 1m0s
[23:43:12.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:31:0)
[23:43:12.911] Timer tick, asking Buildbucket for the build status
[23:43:13.199] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:13.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:32:0) after 1m0s
[23:44:13.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:32:0)
[23:44:13.241] Timer tick, asking Buildbucket for the build status
[23:44:13.327] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:13.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:33:0) after 1m0s
[23:45:13.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:33:0)
[23:45:13.346] Timer tick, asking Buildbucket for the build status
[23:45:13.517] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:13.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:34:0) after 1m0s
[23:46:13.545] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:34:0)
[23:46:13.545] Timer tick, asking Buildbucket for the build status
[23:46:13.778] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:13.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:35:0) after 1m0s
[23:47:13.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:35:0)
[23:47:13.794] Timer tick, asking Buildbucket for the build status
[23:47:14.021] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:14.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:36:0) after 1m0s
[23:48:14.040] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:36:0)
[23:48:14.040] Timer tick, asking Buildbucket for the build status
[23:48:14.264] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:14.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:37:0) after 1m0s
[23:49:14.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:37:0)
[23:49:14.281] Timer tick, asking Buildbucket for the build status
[23:49:14.511] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:14.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:38:0) after 1m0s
[23:50:14.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:38:0)
[23:50:14.542] Timer tick, asking Buildbucket for the build status
[23:50:14.758] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:14.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:39:0) after 1m0s
[23:51:14.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:39:0)
[23:51:14.774] Timer tick, asking Buildbucket for the build status
[23:51:15.003] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:15.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:40:0) after 1m0s
[23:52:15.023] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:40:0)
[23:52:15.023] Timer tick, asking Buildbucket for the build status
[23:52:15.096] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:15.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:41:0) after 1m0s
[23:53:15.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:41:0)
[23:53:15.117] Timer tick, asking Buildbucket for the build status
[23:53:15.185] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:15.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:42:0) after 1m0s
[23:54:15.230] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:42:0)
[23:54:15.230] Timer tick, asking Buildbucket for the build status
[23:54:15.362] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:15.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:43:0) after 1m0s
[23:55:15.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:43:0)
[23:55:15.404] Timer tick, asking Buildbucket for the build status
[23:55:16.120] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:16.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:44:0) after 1m0s
[23:56:16.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:44:0)
[23:56:16.148] Timer tick, asking Buildbucket for the build status
[23:56:16.271] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:16.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:45:0) after 1m0s
[23:57:16.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:45:0)
[23:57:16.292] Timer tick, asking Buildbucket for the build status
[23:57:16.363] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:16.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:46:0) after 1m0s
[23:58:16.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:46:0)
[23:58:16.379] Timer tick, asking Buildbucket for the build status
[23:58:16.849] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:16.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:47:0) after 1m0s
[23:59:16.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:47:0)
[23:59:16.867] Timer tick, asking Buildbucket for the build status
[23:59:16.928] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:16.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:48:0) after 1m0s
[00:00:16.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:48:0)
[00:00:16.945] Timer tick, asking Buildbucket for the build status
[00:00:17.889] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:17.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:49:0) after 1m0s
[00:01:17.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:49:0)
[00:01:17.907] Timer tick, asking Buildbucket for the build status
[00:01:17.972] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:17.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:50:0) after 1m0s
[00:02:17.991] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:50:0)
[00:02:17.991] Timer tick, asking Buildbucket for the build status
[00:02:18.065] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:18.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:51:0) after 1m0s
[00:03:18.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:51:0)
[00:03:18.149] Timer tick, asking Buildbucket for the build status
[00:03:20.520] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:20.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:52:0) after 1m0s
[00:04:20.555] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:52:0)
[00:04:20.555] Timer tick, asking Buildbucket for the build status
[00:04:20.626] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:20.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:53:0) after 1m0s
[00:05:20.643] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:53:0)
[00:05:20.643] Timer tick, asking Buildbucket for the build status
[00:05:20.740] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:20.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:54:0) after 1m0s
[00:06:20.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:54:0)
[00:06:20.829] Timer tick, asking Buildbucket for the build status
[00:06:20.952] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:20.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:55:0) after 1m0s
[00:07:20.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:55:0)
[00:07:20.975] Timer tick, asking Buildbucket for the build status
[00:07:21.043] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:21.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:56:0) after 1m0s
[00:08:21.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:56:0)
[00:08:21.063] Timer tick, asking Buildbucket for the build status
[00:08:21.365] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:21.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:57:0) after 1m0s
[00:09:21.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:57:0)
[00:09:21.384] Timer tick, asking Buildbucket for the build status
[00:09:21.588] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:21.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:58:0) after 1m0s
[00:10:21.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:58:0)
[00:10:21.606] Timer tick, asking Buildbucket for the build status
[00:10:21.775] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:21.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:59:0) after 1m0s
[00:11:21.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:59:0)
[00:11:21.800] Timer tick, asking Buildbucket for the build status
[00:11:22.179] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:22.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:60:0) after 1m0s
[00:12:22.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:60:0)
[00:12:22.410] Timer tick, asking Buildbucket for the build status
[00:12:22.696] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:22.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:61:0) after 1m0s
[00:13:22.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:61:0)
[00:13:22.719] Timer tick, asking Buildbucket for the build status
[00:13:23.194] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:23.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:62:0) after 1m0s
[00:14:23.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:62:0)
[00:14:23.213] Timer tick, asking Buildbucket for the build status
[00:14:23.295] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:23.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:63:0) after 1m0s
[00:15:23.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:63:0)
[00:15:23.293] Timer tick, asking Buildbucket for the build status
[00:15:23.397] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:23.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:64:0) after 1m0s
[00:16:23.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:64:0)
[00:16:23.415] Timer tick, asking Buildbucket for the build status
[00:16:23.549] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:23.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:65:0) after 1m0s
[00:17:23.573] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:65:0)
[00:17:23.573] Timer tick, asking Buildbucket for the build status
[00:17:23.678] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:23.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:66:0) after 1m0s
[00:18:23.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:66:0)
[00:18:23.696] Timer tick, asking Buildbucket for the build status
[00:18:23.775] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:23.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:67:0) after 1m0s
[00:19:23.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:67:0)
[00:19:23.792] Timer tick, asking Buildbucket for the build status
[00:19:23.878] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:23.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:68:0) after 1m0s
[00:20:23.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:68:0)
[00:20:23.896] Timer tick, asking Buildbucket for the build status
[00:20:23.959] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:23.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:69:0) after 1m0s
[00:21:24.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:69:0)
[00:21:24.004] Timer tick, asking Buildbucket for the build status
[00:21:24.277] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:24.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:70:0) after 1m0s
[00:22:24.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:70:0)
[00:22:24.306] Timer tick, asking Buildbucket for the build status
[00:22:24.639] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:24.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:71:0) after 1m0s
[00:23:24.659] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:71:0)
[00:23:24.659] Timer tick, asking Buildbucket for the build status
[00:23:24.908] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:24.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:72:0) after 1m0s
[00:24:24.903] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:72:0)
[00:24:24.903] Timer tick, asking Buildbucket for the build status
[00:24:25.012] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:25.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:73:0) after 1m0s
[00:25:25.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:73:0)
[00:25:25.057] Timer tick, asking Buildbucket for the build status
[00:25:25.212] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:25.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:74:0) after 1m0s
[00:26:25.232] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:74:0)
[00:26:25.232] Timer tick, asking Buildbucket for the build status
[00:26:25.409] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:25.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:75:0) after 1m0s
[00:27:25.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:75:0)
[00:27:25.429] Timer tick, asking Buildbucket for the build status
[00:27:25.725] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:25.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:76:0) after 1m0s
[00:28:25.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:76:0)
[00:28:25.745] Timer tick, asking Buildbucket for the build status
[00:28:25.821] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:25.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:77:0) after 1m0s
[00:29:25.844] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:77:0)
[00:29:25.844] Timer tick, asking Buildbucket for the build status
[00:29:25.910] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:25.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:78:0) after 1m0s
[00:30:25.932] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:78:0)
[00:30:25.932] Timer tick, asking Buildbucket for the build status
[00:30:26.061] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:26.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:79:0) after 1m0s
[00:31:26.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:79:0)
[00:31:26.106] Timer tick, asking Buildbucket for the build status
[00:31:26.203] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:26.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:80:0) after 1m0s
[00:32:26.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:80:0)
[00:32:26.222] Timer tick, asking Buildbucket for the build status
[00:32:26.325] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:26.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:81:0) after 1m0s
[00:33:26.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:81:0)
[00:33:26.363] Timer tick, asking Buildbucket for the build status
[00:33:26.465] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:26.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:82:0) after 1m0s
[00:34:26.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:82:0)
[00:34:26.489] Timer tick, asking Buildbucket for the build status
[00:34:26.990] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:26.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:83:0) after 1m0s
[00:35:27.014] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:83:0)
[00:35:27.014] Timer tick, asking Buildbucket for the build status
[00:35:27.177] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:27.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:84:0) after 1m0s
[00:36:27.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:84:0)
[00:36:27.177] Timer tick, asking Buildbucket for the build status
[00:36:27.229] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:27.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:85:0) after 1m0s
[00:37:27.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:85:0)
[00:37:27.259] Timer tick, asking Buildbucket for the build status
[00:37:27.431] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:27.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:86:0) after 1m0s
[00:38:27.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:86:0)
[00:38:27.457] Timer tick, asking Buildbucket for the build status
[00:38:27.524] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:27.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:87:0) after 1m0s
[00:39:27.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:87:0)
[00:39:27.543] Timer tick, asking Buildbucket for the build status
[00:39:27.700] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:27.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:88:0) after 1m0s
[00:40:27.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:88:0)
[00:40:27.720] Timer tick, asking Buildbucket for the build status
[00:40:28.275] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:28.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:89:0) after 1m0s
[00:41:28.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:89:0)
[00:41:28.316] Timer tick, asking Buildbucket for the build status
[00:41:28.395] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:28.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:90:0) after 1m0s
[00:42:28.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:90:0)
[00:42:28.433] Timer tick, asking Buildbucket for the build status
[00:42:28.515] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:28.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:91:0) after 1m0s
[00:43:28.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:91:0)
[00:43:28.571] Timer tick, asking Buildbucket for the build status
[00:43:28.658] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:28.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:92:0) after 1m0s
[00:44:28.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:92:0)
[00:44:28.698] Timer tick, asking Buildbucket for the build status
[00:44:29.201] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:29.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:93:0) after 1m0s
[00:45:29.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:93:0)
[00:45:29.188] Timer tick, asking Buildbucket for the build status
[00:45:29.442] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:29.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:94:0) after 1m0s
[00:46:29.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:94:0)
[00:46:29.458] Timer tick, asking Buildbucket for the build status
[00:46:29.608] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:29.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:95:0) after 1m0s
[00:47:29.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:95:0)
[00:47:29.630] Timer tick, asking Buildbucket for the build status
[00:47:29.693] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:29.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:96:0) after 1m0s
[00:48:29.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:96:0)
[00:48:29.713] Timer tick, asking Buildbucket for the build status
[00:48:29.781] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:29.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:97:0) after 1m0s
[00:49:29.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:97:0)
[00:49:29.802] Timer tick, asking Buildbucket for the build status
[00:49:29.902] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:29.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:98:0) after 1m0s
[00:50:29.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:98:0)
[00:50:29.922] Timer tick, asking Buildbucket for the build status
[00:50:29.978] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:29.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:99:0) after 1m0s
[00:51:29.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:99:0)
[00:51:29.998] Timer tick, asking Buildbucket for the build status
[00:51:30.123] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:30.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:100:0) after 1m0s
[00:52:30.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:100:0)
[00:52:30.144] Timer tick, asking Buildbucket for the build status
[00:52:30.209] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:30.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:101:0) after 1m0s
[00:53:30.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:101:0)
[00:53:30.208] Timer tick, asking Buildbucket for the build status
[00:53:30.268] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:30.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:102:0) after 1m0s
[00:54:30.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:102:0)
[00:54:30.291] Timer tick, asking Buildbucket for the build status
[00:54:30.328] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:30.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:103:0) after 1m0s
[00:55:30.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:103:0)
[00:55:30.355] Timer tick, asking Buildbucket for the build status
[00:55:30.421] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:30.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:104:0) after 1m0s
[00:56:30.444] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:104:0)
[00:56:30.444] Timer tick, asking Buildbucket for the build status
[00:56:30.550] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:30.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:105:0) after 1m0s
[00:57:30.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:105:0)
[00:57:30.571] Timer tick, asking Buildbucket for the build status
[00:57:30.779] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:30.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:106:0) after 1m0s
[00:58:30.842] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:106:0)
[00:58:30.842] Timer tick, asking Buildbucket for the build status
[00:58:31.004] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:31.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:107:0) after 1m0s
[00:59:31.025] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:107:0)
[00:59:31.025] Timer tick, asking Buildbucket for the build status
[00:59:31.092] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:31.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:108:0) after 1m0s
[01:00:31.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:108:0)
[01:00:31.116] Timer tick, asking Buildbucket for the build status
[01:00:31.179] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:31.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:109:0) after 1m0s
[01:01:31.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:109:0)
[01:01:31.201] Timer tick, asking Buildbucket for the build status
[01:01:31.284] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:31.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:110:0) after 1m0s
[01:02:31.310] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:110:0)
[01:02:31.310] Timer tick, asking Buildbucket for the build status
[01:02:31.363] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:31.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:111:0) after 1m0s
[01:03:31.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:111:0)
[01:03:31.389] Timer tick, asking Buildbucket for the build status
[01:03:31.469] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:31.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:112:0) after 1m0s
[01:04:31.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:112:0)
[01:04:31.577] Timer tick, asking Buildbucket for the build status
[01:04:31.620] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:31.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:113:0) after 1m0s
[01:05:31.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:113:0)
[01:05:31.604] Timer tick, asking Buildbucket for the build status
[01:05:31.778] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:31.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:114:0) after 1m0s
[01:06:31.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:114:0)
[01:06:31.809] Timer tick, asking Buildbucket for the build status
[01:06:32.008] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:32.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:115:0) after 1m0s
[01:07:32.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:115:0)
[01:07:32.057] Timer tick, asking Buildbucket for the build status
[01:07:32.121] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:32.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:116:0) after 1m0s
[01:08:32.142] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:116:0)
[01:08:32.142] Timer tick, asking Buildbucket for the build status
[01:08:32.263] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:32.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:117:0) after 1m0s
[01:09:32.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:117:0)
[01:09:32.306] Timer tick, asking Buildbucket for the build status
[01:09:32.396] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:32.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:118:0) after 1m0s
[01:10:32.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:118:0)
[01:10:32.503] Timer tick, asking Buildbucket for the build status
[01:10:32.769] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:32.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:119:0) after 1m0s
[01:11:32.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:119:0)
[01:11:32.826] Timer tick, asking Buildbucket for the build status
[01:11:33.010] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:33.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:120:0) after 1m0s
[01:12:33.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:120:0)
[01:12:33.049] Timer tick, asking Buildbucket for the build status
[01:12:33.113] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:33.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:121:0) after 1m0s
[01:13:33.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:121:0)
[01:13:33.136] Timer tick, asking Buildbucket for the build status
[01:13:33.192] Build 8917519564170744688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:33.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082949626290524192:122:0) after 1m0s
[01:14:32.447] Received PubSub notification, asking Buildbucket for the build status
[01:14:32.515] Build 8917519564170744688: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:14:32.515] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553994872090434",
"created_by": "project:fuchsia",
"created_ts": "1553987647808886",
"failure_reason": "BUILD_FAILURE",
"id": "8917519564170744688",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a28ee7ec4f07bee0145ff9bb74693009a5be2126\"}}",
"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-c-c81b\", \"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\": \"a28ee7ec4f07bee0145ff9bb74693009a5be2126\", \"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-c-c81b\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4226-cfe6277\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1553987651338266",
"status": "COMPLETED",
"status_changed_ts": "1553987652056469",
"tags": [
"builder:clang",
"buildset:commit/git/a28ee7ec4f07bee0145ff9bb74693009a5be2126",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a28ee7ec4f07bee0145ff9bb74693009a5be2126",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082949626290524192",
"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/8917519564170744688/+/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:43e9b344041b7710",
"user_agent:luci-scheduler"
],
"updated_ts": "1553994872200491",
"url": "https://ci.chromium.org/b/8917519564170744688",
"utcnow_ts": "1553994872507255"
}
}
[01:14:32.515] Invocation finished in 2h0m26.11325891s with status FAILED