[02:20:14.101] New invocation is queued and will start shortly
[02:20:15.196] Starting the invocation (attempt 1)
[02:20:15.242] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:20:15.242] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:20:15.242] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081397767698865216",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"5081e41bdae2eb14a3f3eb8810263f9fea8fc7c1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081397767698865216",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/5081e41bdae2eb14a3f3eb8810263f9fea8fc7c1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/5081e41bdae2eb14a3f3eb8810263f9fea8fc7c1",
"gitiles_ref:refs/heads/master"
]
}
[02:20:15.857] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555467615351534",
"id": "8915967705724107584",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"5081e41bdae2eb14a3f3eb8810263f9fea8fc7c1\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555467615699409",
"tags": [
"builder:clang",
"buildset:commit/git/5081e41bdae2eb14a3f3eb8810263f9fea8fc7c1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/5081e41bdae2eb14a3f3eb8810263f9fea8fc7c1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081397767698865216",
"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/8915967705724107584/+/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": "1555467615699506",
"url": "https://ci.chromium.org/b/8915967705724107584",
"utcnow_ts": "1555467615847249"
}
}
[02:20:15.857] Task URL: https://ci.chromium.org/b/8915967705724107584
[02:20:15.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:2:0) after 1m0s
[02:20:39.599] Received PubSub notification, asking Buildbucket for the build status
[02:20:39.681] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:15.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:2:0)
[02:21:15.880] Timer tick, asking Buildbucket for the build status
[02:21:16.084] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:16.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:4:0) after 1m0s
[02:22:16.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:4:0)
[02:22:16.073] Timer tick, asking Buildbucket for the build status
[02:22:16.553] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:16.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:5:0) after 1m0s
[02:23:16.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:5:0)
[02:23:16.568] Timer tick, asking Buildbucket for the build status
[02:23:16.660] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:16.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:6:0) after 1m0s
[02:24:16.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:6:0)
[02:24:16.682] Timer tick, asking Buildbucket for the build status
[02:24:16.731] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:16.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:7:0) after 1m0s
[02:25:16.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:7:0)
[02:25:16.749] Timer tick, asking Buildbucket for the build status
[02:25:16.869] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:16.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:8:0) after 1m0s
[02:26:16.863] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:8:0)
[02:26:16.863] Timer tick, asking Buildbucket for the build status
[02:26:17.003] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:17.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:9:0) after 1m0s
[02:27:17.022] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:9:0)
[02:27:17.022] Timer tick, asking Buildbucket for the build status
[02:27:17.069] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:17.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:10:0) after 1m0s
[02:28:17.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:10:0)
[02:28:17.087] Timer tick, asking Buildbucket for the build status
[02:28:17.152] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:17.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:11:0) after 1m0s
[02:29:17.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:11:0)
[02:29:17.174] Timer tick, asking Buildbucket for the build status
[02:29:17.273] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:17.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:12:0) after 1m0s
[02:30:17.313] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:12:0)
[02:30:17.313] Timer tick, asking Buildbucket for the build status
[02:30:17.459] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:17.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:13:0) after 1m0s
[02:31:17.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:13:0)
[02:31:17.478] Timer tick, asking Buildbucket for the build status
[02:31:17.593] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:17.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:14:0) after 1m0s
[02:32:17.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:14:0)
[02:32:17.610] Timer tick, asking Buildbucket for the build status
[02:32:17.813] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:17.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:15:0) after 1m0s
[02:33:17.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:15:0)
[02:33:17.803] Timer tick, asking Buildbucket for the build status
[02:33:17.956] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:17.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:16:0) after 1m0s
[02:34:17.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:16:0)
[02:34:17.979] Timer tick, asking Buildbucket for the build status
[02:34:18.066] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:18.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:17:0) after 1m0s
[02:35:18.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:17:0)
[02:35:18.089] Timer tick, asking Buildbucket for the build status
[02:35:18.188] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:18.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:18:0) after 1m0s
[02:36:18.184] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:18:0)
[02:36:18.184] Timer tick, asking Buildbucket for the build status
[02:36:18.227] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:18.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:19:0) after 1m0s
[02:37:18.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:19:0)
[02:37:18.246] Timer tick, asking Buildbucket for the build status
[02:37:18.385] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:18.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:20:0) after 1m0s
[02:38:18.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:20:0)
[02:38:18.382] Timer tick, asking Buildbucket for the build status
[02:38:18.426] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:18.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:21:0) after 1m0s
[02:39:18.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:21:0)
[02:39:18.472] Timer tick, asking Buildbucket for the build status
[02:39:18.575] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:18.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:22:0) after 1m0s
[02:40:18.663] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:22:0)
[02:40:18.663] Timer tick, asking Buildbucket for the build status
[02:40:18.761] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:18.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:23:0) after 1m0s
[02:41:18.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:23:0)
[02:41:18.789] Timer tick, asking Buildbucket for the build status
[02:41:18.893] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:18.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:24:0) after 1m0s
[02:42:18.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:24:0)
[02:42:18.916] Timer tick, asking Buildbucket for the build status
[02:42:18.981] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:18.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:25:0) after 1m0s
[02:43:18.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:25:0)
[02:43:18.999] Timer tick, asking Buildbucket for the build status
[02:43:19.295] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:19.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:26:0) after 1m0s
[02:44:19.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:26:0)
[02:44:19.280] Timer tick, asking Buildbucket for the build status
[02:44:19.339] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:19.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:27:0) after 1m0s
[02:45:19.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:27:0)
[02:45:19.348] Timer tick, asking Buildbucket for the build status
[02:45:19.393] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:19.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:28:0) after 1m0s
[02:46:19.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:28:0)
[02:46:19.384] Timer tick, asking Buildbucket for the build status
[02:46:19.569] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:19.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:29:0) after 1m0s
[02:47:19.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:29:0)
[02:47:19.610] Timer tick, asking Buildbucket for the build status
[02:47:19.665] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:19.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:30:0) after 1m0s
[02:48:19.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:30:0)
[02:48:19.687] Timer tick, asking Buildbucket for the build status
[02:48:19.771] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:19.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:31:0) after 1m0s
[02:49:19.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:31:0)
[02:49:19.848] Timer tick, asking Buildbucket for the build status
[02:49:19.896] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:19.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:32:0) after 1m0s
[02:50:19.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:32:0)
[02:50:19.871] Timer tick, asking Buildbucket for the build status
[02:50:19.937] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:19.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:33:0) after 1m0s
[02:51:19.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:33:0)
[02:51:19.965] Timer tick, asking Buildbucket for the build status
[02:51:20.003] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:20.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:34:0) after 1m0s
[02:52:20.025] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:34:0)
[02:52:20.025] Timer tick, asking Buildbucket for the build status
[02:52:20.100] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:20.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:35:0) after 1m0s
[02:53:20.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:35:0)
[02:53:20.116] Timer tick, asking Buildbucket for the build status
[02:53:20.176] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:20.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:36:0) after 1m0s
[02:54:20.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:36:0)
[02:54:20.197] Timer tick, asking Buildbucket for the build status
[02:54:20.472] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:20.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:37:0) after 1m0s
[02:55:20.494] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:37:0)
[02:55:20.494] Timer tick, asking Buildbucket for the build status
[02:55:20.534] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:20.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:38:0) after 1m0s
[02:56:20.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:38:0)
[02:56:20.569] Timer tick, asking Buildbucket for the build status
[02:56:20.772] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:20.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:39:0) after 1m0s
[02:57:20.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:39:0)
[02:57:20.761] Timer tick, asking Buildbucket for the build status
[02:57:20.806] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:20.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:40:0) after 1m0s
[02:58:20.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:40:0)
[02:58:20.826] Timer tick, asking Buildbucket for the build status
[02:58:21.179] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:21.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:41:0) after 1m0s
[02:59:21.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:41:0)
[02:59:21.167] Timer tick, asking Buildbucket for the build status
[02:59:21.221] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:21.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:42:0) after 1m0s
[03:00:21.240] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:42:0)
[03:00:21.240] Timer tick, asking Buildbucket for the build status
[03:00:21.436] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:21.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:43:0) after 1m0s
[03:01:21.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:43:0)
[03:01:21.457] Timer tick, asking Buildbucket for the build status
[03:01:21.502] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:21.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:44:0) after 1m0s
[03:02:21.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:44:0)
[03:02:21.472] Timer tick, asking Buildbucket for the build status
[03:02:21.537] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:21.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:45:0) after 1m0s
[03:03:21.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:45:0)
[03:03:21.513] Timer tick, asking Buildbucket for the build status
[03:03:21.624] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:21.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:46:0) after 1m0s
[03:04:21.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:46:0)
[03:04:21.644] Timer tick, asking Buildbucket for the build status
[03:04:21.689] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:21.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:47:0) after 1m0s
[03:05:21.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:47:0)
[03:05:21.707] Timer tick, asking Buildbucket for the build status
[03:05:21.771] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:21.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:48:0) after 1m0s
[03:06:21.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:48:0)
[03:06:21.790] Timer tick, asking Buildbucket for the build status
[03:06:21.826] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:21.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:49:0) after 1m0s
[03:07:21.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:49:0)
[03:07:21.854] Timer tick, asking Buildbucket for the build status
[03:07:21.985] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:21.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:50:0) after 1m0s
[03:08:22.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:50:0)
[03:08:22.002] Timer tick, asking Buildbucket for the build status
[03:08:22.083] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:22.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:51:0) after 1m0s
[03:09:22.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:51:0)
[03:09:22.088] Timer tick, asking Buildbucket for the build status
[03:09:22.262] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:22.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:52:0) after 1m0s
[03:10:22.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:52:0)
[03:10:22.283] Timer tick, asking Buildbucket for the build status
[03:10:22.326] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:22.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:53:0) after 1m0s
[03:11:22.341] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:53:0)
[03:11:22.341] Timer tick, asking Buildbucket for the build status
[03:11:22.401] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:22.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:54:0) after 1m0s
[03:12:22.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:54:0)
[03:12:22.414] Timer tick, asking Buildbucket for the build status
[03:12:22.479] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:22.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:55:0) after 1m0s
[03:13:22.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:55:0)
[03:13:22.457] Timer tick, asking Buildbucket for the build status
[03:13:22.589] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:22.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:56:0) after 1m0s
[03:14:22.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:56:0)
[03:14:22.568] Timer tick, asking Buildbucket for the build status
[03:14:22.606] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:22.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:57:0) after 1m0s
[03:15:22.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:57:0)
[03:15:22.592] Timer tick, asking Buildbucket for the build status
[03:15:22.724] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:22.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:58:0) after 1m0s
[03:16:22.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:58:0)
[03:16:22.745] Timer tick, asking Buildbucket for the build status
[03:16:23.028] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:23.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:59:0) after 1m0s
[03:17:23.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:59:0)
[03:17:23.049] Timer tick, asking Buildbucket for the build status
[03:17:23.169] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:23.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:60:0) after 1m0s
[03:18:23.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:60:0)
[03:18:23.183] Timer tick, asking Buildbucket for the build status
[03:18:23.248] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:23.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:61:0) after 1m0s
[03:19:23.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:61:0)
[03:19:23.331] Timer tick, asking Buildbucket for the build status
[03:19:23.478] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:23.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:62:0) after 1m0s
[03:20:23.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:62:0)
[03:20:23.486] Timer tick, asking Buildbucket for the build status
[03:20:23.546] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:23.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:63:0) after 1m0s
[03:21:23.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:63:0)
[03:21:23.568] Timer tick, asking Buildbucket for the build status
[03:21:23.775] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:23.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:64:0) after 1m0s
[03:22:23.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:64:0)
[03:22:23.791] Timer tick, asking Buildbucket for the build status
[03:22:23.958] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:23.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:65:0) after 1m0s
[03:23:24.058] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:65:0)
[03:23:24.058] Timer tick, asking Buildbucket for the build status
[03:23:24.224] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:24.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:66:0) after 1m0s
[03:24:24.252] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:66:0)
[03:24:24.252] Timer tick, asking Buildbucket for the build status
[03:24:24.313] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:24.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:67:0) after 1m0s
[03:25:24.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:67:0)
[03:25:24.287] Timer tick, asking Buildbucket for the build status
[03:25:24.341] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:24.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:68:0) after 1m0s
[03:26:24.344] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:68:0)
[03:26:24.344] Timer tick, asking Buildbucket for the build status
[03:26:24.422] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:24.422] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:69:0) after 1m0s
[03:27:24.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:69:0)
[03:27:24.405] Timer tick, asking Buildbucket for the build status
[03:27:24.500] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:24.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:70:0) after 1m0s
[03:28:24.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:70:0)
[03:28:24.507] Timer tick, asking Buildbucket for the build status
[03:28:24.572] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:24.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:71:0) after 1m0s
[03:29:24.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:71:0)
[03:29:24.590] Timer tick, asking Buildbucket for the build status
[03:29:24.709] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:24.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:72:0) after 1m0s
[03:30:24.729] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:72:0)
[03:30:24.730] Timer tick, asking Buildbucket for the build status
[03:30:24.858] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:24.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:73:0) after 1m0s
[03:31:24.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:73:0)
[03:31:24.862] Timer tick, asking Buildbucket for the build status
[03:31:24.927] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:24.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:74:0) after 1m0s
[03:32:24.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:74:0)
[03:32:24.950] Timer tick, asking Buildbucket for the build status
[03:32:25.015] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:25.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:75:0) after 1m0s
[03:33:25.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:75:0)
[03:33:25.036] Timer tick, asking Buildbucket for the build status
[03:33:25.108] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:25.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:76:0) after 1m0s
[03:34:25.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:76:0)
[03:34:25.134] Timer tick, asking Buildbucket for the build status
[03:34:25.277] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:25.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:77:0) after 1m0s
[03:35:25.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:77:0)
[03:35:25.281] Timer tick, asking Buildbucket for the build status
[03:35:25.917] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:25.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:78:0) after 1m0s
[03:36:25.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:78:0)
[03:36:25.938] Timer tick, asking Buildbucket for the build status
[03:36:26.000] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:26.000] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:79:0) after 1m0s
[03:37:26.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:79:0)
[03:37:26.053] Timer tick, asking Buildbucket for the build status
[03:37:26.124] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:26.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:80:0) after 1m0s
[03:38:26.151] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:80:0)
[03:38:26.151] Timer tick, asking Buildbucket for the build status
[03:38:26.214] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:26.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:81:0) after 1m0s
[03:39:26.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:81:0)
[03:39:26.196] Timer tick, asking Buildbucket for the build status
[03:39:26.246] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:26.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:82:0) after 1m0s
[03:40:26.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:82:0)
[03:40:26.318] Timer tick, asking Buildbucket for the build status
[03:40:26.584] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:26.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:83:0) after 1m0s
[03:41:26.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:83:0)
[03:41:26.607] Timer tick, asking Buildbucket for the build status
[03:41:26.643] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:26.643] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:84:0) after 1m0s
[03:42:26.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:84:0)
[03:42:26.665] Timer tick, asking Buildbucket for the build status
[03:42:26.802] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:26.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:85:0) after 1m0s
[03:43:26.825] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:85:0)
[03:43:26.825] Timer tick, asking Buildbucket for the build status
[03:43:26.871] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:26.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:86:0) after 1m0s
[03:44:26.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:86:0)
[03:44:26.900] Timer tick, asking Buildbucket for the build status
[03:44:26.964] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:26.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:87:0) after 1m0s
[03:45:27.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:87:0)
[03:45:27.002] Timer tick, asking Buildbucket for the build status
[03:45:27.056] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:27.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:88:0) after 1m0s
[03:46:27.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:88:0)
[03:46:27.036] Timer tick, asking Buildbucket for the build status
[03:46:27.122] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:27.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:89:0) after 1m0s
[03:47:27.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:89:0)
[03:47:27.144] Timer tick, asking Buildbucket for the build status
[03:47:27.207] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:27.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:90:0) after 1m0s
[03:48:27.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:90:0)
[03:48:27.228] Timer tick, asking Buildbucket for the build status
[03:48:27.306] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:27.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:91:0) after 1m0s
[03:49:27.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:91:0)
[03:49:27.348] Timer tick, asking Buildbucket for the build status
[03:49:27.393] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:27.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:92:0) after 1m0s
[03:50:27.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:92:0)
[03:50:27.415] Timer tick, asking Buildbucket for the build status
[03:50:27.458] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:27.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:93:0) after 1m0s
[03:51:27.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:93:0)
[03:51:27.483] Timer tick, asking Buildbucket for the build status
[03:51:27.646] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:27.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:94:0) after 1m0s
[03:52:27.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:94:0)
[03:52:27.688] Timer tick, asking Buildbucket for the build status
[03:52:27.776] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:27.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:95:0) after 1m0s
[03:53:27.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:95:0)
[03:53:27.821] Timer tick, asking Buildbucket for the build status
[03:53:27.989] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:27.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:96:0) after 1m0s
[03:54:28.011] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:96:0)
[03:54:28.011] Timer tick, asking Buildbucket for the build status
[03:54:28.085] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:28.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:97:0) after 1m0s
[03:55:28.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:97:0)
[03:55:28.116] Timer tick, asking Buildbucket for the build status
[03:55:28.163] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:28.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:98:0) after 1m0s
[03:56:28.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:98:0)
[03:56:28.149] Timer tick, asking Buildbucket for the build status
[03:56:28.264] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:28.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:99:0) after 1m0s
[03:57:28.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:99:0)
[03:57:28.264] Timer tick, asking Buildbucket for the build status
[03:57:28.362] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:28.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:100:0) after 1m0s
[03:58:28.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:100:0)
[03:58:28.386] Timer tick, asking Buildbucket for the build status
[03:58:28.539] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:28.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:101:0) after 1m0s
[03:59:28.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:101:0)
[03:59:28.561] Timer tick, asking Buildbucket for the build status
[03:59:28.632] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:28.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:102:0) after 1m0s
[04:00:28.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:102:0)
[04:00:28.614] Timer tick, asking Buildbucket for the build status
[04:00:28.874] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:28.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:103:0) after 1m0s
[04:01:28.903] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:103:0)
[04:01:28.903] Timer tick, asking Buildbucket for the build status
[04:01:28.972] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:28.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:104:0) after 1m0s
[04:02:28.963] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:104:0)
[04:02:28.963] Timer tick, asking Buildbucket for the build status
[04:02:29.022] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:29.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:105:0) after 1m0s
[04:03:29.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:105:0)
[04:03:29.069] Timer tick, asking Buildbucket for the build status
[04:03:29.157] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:29.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:106:0) after 1m0s
[04:04:29.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:106:0)
[04:04:29.206] Timer tick, asking Buildbucket for the build status
[04:04:29.411] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:29.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:107:0) after 1m0s
[04:05:29.437] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:107:0)
[04:05:29.437] Timer tick, asking Buildbucket for the build status
[04:05:29.531] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:29.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:108:0) after 1m0s
[04:06:29.555] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:108:0)
[04:06:29.555] Timer tick, asking Buildbucket for the build status
[04:06:29.608] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:29.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:109:0) after 1m0s
[04:07:29.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:109:0)
[04:07:29.616] Timer tick, asking Buildbucket for the build status
[04:07:29.673] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:29.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:110:0) after 1m0s
[04:08:29.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:110:0)
[04:08:29.699] Timer tick, asking Buildbucket for the build status
[04:08:29.798] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:29.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:111:0) after 1m0s
[04:09:29.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:111:0)
[04:09:29.791] Timer tick, asking Buildbucket for the build status
[04:09:29.835] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:29.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:112:0) after 1m0s
[04:10:29.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:112:0)
[04:10:29.865] Timer tick, asking Buildbucket for the build status
[04:10:29.928] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:29.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:113:0) after 1m0s
[04:11:29.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:113:0)
[04:11:29.950] Timer tick, asking Buildbucket for the build status
[04:11:30.001] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:30.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:114:0) after 1m0s
[04:12:30.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:114:0)
[04:12:30.049] Timer tick, asking Buildbucket for the build status
[04:12:30.132] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:30.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:115:0) after 1m0s
[04:13:30.160] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:115:0)
[04:13:30.160] Timer tick, asking Buildbucket for the build status
[04:13:30.208] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:30.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:116:0) after 1m0s
[04:14:30.258] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:116:0)
[04:14:30.258] Timer tick, asking Buildbucket for the build status
[04:14:30.328] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:30.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:117:0) after 1m0s
[04:15:30.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:117:0)
[04:15:30.314] Timer tick, asking Buildbucket for the build status
[04:15:30.462] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:30.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:118:0) after 1m0s
[04:16:30.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:118:0)
[04:16:30.484] Timer tick, asking Buildbucket for the build status
[04:16:30.524] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:30.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:119:0) after 1m0s
[04:17:30.573] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:119:0)
[04:17:30.573] Timer tick, asking Buildbucket for the build status
[04:17:30.648] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:30.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:120:0) after 1m0s
[04:18:30.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:120:0)
[04:18:30.712] Timer tick, asking Buildbucket for the build status
[04:18:30.770] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:30.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:121:0) after 1m0s
[04:19:30.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:121:0)
[04:19:30.794] Timer tick, asking Buildbucket for the build status
[04:19:30.961] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:30.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:122:0) after 1m0s
[04:20:30.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:122:0)
[04:20:30.985] Timer tick, asking Buildbucket for the build status
[04:20:31.045] Build 8915967705724107584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:31.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081397767698865216:123:0) after 1m0s
[04:21:15.946] Received PubSub notification, asking Buildbucket for the build status
[04:21:15.999] Build 8915967705724107584: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:21:15.999] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555474874476686",
"created_by": "project:fuchsia",
"created_ts": "1555467615351534",
"failure_reason": "BUILD_FAILURE",
"id": "8915967705724107584",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"5081e41bdae2eb14a3f3eb8810263f9fea8fc7c1\"}}",
"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-r6tk\", \"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\": \"5081e41bdae2eb14a3f3eb8810263f9fea8fc7c1\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-c-r6tk\"], \"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\": [\"4256-d1e1f70\"], \"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": "1555467638981515",
"status": "COMPLETED",
"status_changed_ts": "1555467639386788",
"tags": [
"builder:clang",
"buildset:commit/git/5081e41bdae2eb14a3f3eb8810263f9fea8fc7c1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/5081e41bdae2eb14a3f3eb8810263f9fea8fc7c1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081397767698865216",
"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/8915967705724107584/+/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:4441e9c6fab59210",
"user_agent:luci-scheduler"
],
"updated_ts": "1555474874639012",
"url": "https://ci.chromium.org/b/8915967705724107584",
"utcnow_ts": "1555474875990054"
}
}
[04:21:15.999] Invocation finished in 2h1m1.914316078s with status FAILED