[13:32:48.175] New invocation is queued and will start shortly
[13:32:49.296] Starting the invocation (attempt 1)
[13:32:49.339] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:32:49.339] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:32:49.339] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9085069929007131824",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"16d98c206b75080d5057ec639c92a460c8be9456\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9085069929007131824",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/16d98c206b75080d5057ec639c92a460c8be9456",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/16d98c206b75080d5057ec639c92a460c8be9456",
"gitiles_ref:refs/heads/master"
]
}
[13:32:51.563] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551965569674039",
"id": "8919639866771784576",
"lease_expiration_ts": "1552091570894095",
"lease_key": "330867981",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"16d98c206b75080d5057ec639c92a460c8be9456\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551965571196041",
"tags": [
"builder:clang",
"buildset:commit/git/16d98c206b75080d5057ec639c92a460c8be9456",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/16d98c206b75080d5057ec639c92a460c8be9456",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085069929007131824",
"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/8919639866771784576/+/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:43712ccd010ad910",
"user_agent:luci-scheduler"
],
"updated_ts": "1551965571196144",
"url": "https://ci.chromium.org/b/8919639866771784576",
"utcnow_ts": "1551965571548726"
}
}
[13:32:51.563] Task URL: https://ci.chromium.org/b/8919639866771784576
[13:32:51.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:2:0) after 1m0s
[13:33:14.129] Received PubSub notification, asking Buildbucket for the build status
[13:33:14.282] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:51.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:2:0)
[13:33:51.581] Timer tick, asking Buildbucket for the build status
[13:33:51.769] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:51.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:4:0) after 1m0s
[13:34:51.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:4:0)
[13:34:51.786] Timer tick, asking Buildbucket for the build status
[13:34:51.917] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:51.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:5:0) after 1m0s
[13:35:51.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:5:0)
[13:35:51.907] Timer tick, asking Buildbucket for the build status
[13:35:52.010] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:52.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:6:0) after 1m0s
[13:36:52.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:6:0)
[13:36:52.028] Timer tick, asking Buildbucket for the build status
[13:36:52.090] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:52.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:7:0) after 1m0s
[13:37:52.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:7:0)
[13:37:52.097] Timer tick, asking Buildbucket for the build status
[13:37:52.162] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:52.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:8:0) after 1m0s
[13:38:52.141] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:8:0)
[13:38:52.141] Timer tick, asking Buildbucket for the build status
[13:38:52.224] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:52.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:9:0) after 1m0s
[13:39:52.256] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:9:0)
[13:39:52.256] Timer tick, asking Buildbucket for the build status
[13:39:52.308] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:52.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:10:0) after 1m0s
[13:40:52.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:10:0)
[13:40:52.358] Timer tick, asking Buildbucket for the build status
[13:40:52.485] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:52.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:11:0) after 1m0s
[13:41:52.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:11:0)
[13:41:52.486] Timer tick, asking Buildbucket for the build status
[13:41:52.548] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:52.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:12:0) after 1m0s
[13:42:52.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:12:0)
[13:42:52.525] Timer tick, asking Buildbucket for the build status
[13:42:52.691] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:52.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:13:0) after 1m0s
[13:43:52.681] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:13:0)
[13:43:52.681] Timer tick, asking Buildbucket for the build status
[13:43:52.731] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:52.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:14:0) after 1m0s
[13:44:52.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:14:0)
[13:44:52.715] Timer tick, asking Buildbucket for the build status
[13:44:52.816] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:52.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:15:0) after 1m0s
[13:45:52.890] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:15:0)
[13:45:52.890] Timer tick, asking Buildbucket for the build status
[13:45:52.975] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:52.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:16:0) after 1m0s
[13:46:52.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:16:0)
[13:46:52.992] Timer tick, asking Buildbucket for the build status
[13:46:53.147] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:53.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:17:0) after 1m0s
[13:47:53.165] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:17:0)
[13:47:53.165] Timer tick, asking Buildbucket for the build status
[13:47:53.230] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:53.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:18:0) after 1m0s
[13:48:53.230] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:18:0)
[13:48:53.230] Timer tick, asking Buildbucket for the build status
[13:48:53.372] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:53.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:19:0) after 1m0s
[13:49:53.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:19:0)
[13:49:53.355] Timer tick, asking Buildbucket for the build status
[13:49:53.407] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:53.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:20:0) after 1m0s
[13:50:53.425] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:20:0)
[13:50:53.425] Timer tick, asking Buildbucket for the build status
[13:50:53.488] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:53.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:21:0) after 1m0s
[13:51:53.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:21:0)
[13:51:53.508] Timer tick, asking Buildbucket for the build status
[13:51:53.593] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:53.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:22:0) after 1m0s
[13:52:53.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:22:0)
[13:52:53.612] Timer tick, asking Buildbucket for the build status
[13:52:53.716] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:53.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:23:0) after 1m0s
[13:53:53.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:23:0)
[13:53:53.725] Timer tick, asking Buildbucket for the build status
[13:53:53.791] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:53.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:24:0) after 1m0s
[13:54:53.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:24:0)
[13:54:53.761] Timer tick, asking Buildbucket for the build status
[13:54:53.891] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:53.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:25:0) after 1m0s
[13:55:53.893] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:25:0)
[13:55:53.893] Timer tick, asking Buildbucket for the build status
[13:55:54.167] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:54.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:26:0) after 1m0s
[13:56:54.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:26:0)
[13:56:54.144] Timer tick, asking Buildbucket for the build status
[13:56:54.370] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:54.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:27:0) after 1m0s
[13:57:54.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:27:0)
[13:57:54.390] Timer tick, asking Buildbucket for the build status
[13:57:54.501] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:54.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:28:0) after 1m0s
[13:58:54.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:28:0)
[13:58:54.508] Timer tick, asking Buildbucket for the build status
[13:58:54.688] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:54.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:29:0) after 1m0s
[13:59:54.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:29:0)
[13:59:54.661] Timer tick, asking Buildbucket for the build status
[13:59:54.712] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:54.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:30:0) after 1m0s
[14:00:54.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:30:0)
[14:00:54.732] Timer tick, asking Buildbucket for the build status
[14:00:54.810] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:54.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:31:0) after 1m0s
[14:01:54.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:31:0)
[14:01:54.808] Timer tick, asking Buildbucket for the build status
[14:01:54.859] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:54.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:32:0) after 1m0s
[14:02:54.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:32:0)
[14:02:54.857] Timer tick, asking Buildbucket for the build status
[14:02:54.923] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:54.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:33:0) after 1m0s
[14:03:54.941] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:33:0)
[14:03:54.941] Timer tick, asking Buildbucket for the build status
[14:03:55.063] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:55.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:34:0) after 1m0s
[14:04:55.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:34:0)
[14:04:55.084] Timer tick, asking Buildbucket for the build status
[14:04:55.206] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:55.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:35:0) after 1m0s
[14:05:55.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:35:0)
[14:05:55.203] Timer tick, asking Buildbucket for the build status
[14:05:55.473] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:55.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:36:0) after 1m0s
[14:06:55.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:36:0)
[14:06:55.478] Timer tick, asking Buildbucket for the build status
[14:06:55.544] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:55.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:37:0) after 1m0s
[14:07:55.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:37:0)
[14:07:55.543] Timer tick, asking Buildbucket for the build status
[14:07:55.598] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:55.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:38:0) after 1m0s
[14:08:55.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:38:0)
[14:08:55.625] Timer tick, asking Buildbucket for the build status
[14:08:55.691] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:55.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:39:0) after 1m0s
[14:09:55.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:39:0)
[14:09:55.709] Timer tick, asking Buildbucket for the build status
[14:09:55.817] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:55.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:40:0) after 1m0s
[14:10:55.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:40:0)
[14:10:55.909] Timer tick, asking Buildbucket for the build status
[14:10:56.112] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:56.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:41:0) after 1m0s
[14:11:56.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:41:0)
[14:11:56.133] Timer tick, asking Buildbucket for the build status
[14:11:56.206] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:56.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:42:0) after 1m0s
[14:12:56.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:42:0)
[14:12:56.222] Timer tick, asking Buildbucket for the build status
[14:12:56.271] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:56.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:43:0) after 1m0s
[14:13:56.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:43:0)
[14:13:56.293] Timer tick, asking Buildbucket for the build status
[14:13:56.392] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:56.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:44:0) after 1m0s
[14:14:56.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:44:0)
[14:14:56.427] Timer tick, asking Buildbucket for the build status
[14:14:56.468] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:56.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:45:0) after 1m0s
[14:15:56.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:45:0)
[14:15:56.496] Timer tick, asking Buildbucket for the build status
[14:15:56.665] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:56.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:46:0) after 1m0s
[14:16:56.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:46:0)
[14:16:56.682] Timer tick, asking Buildbucket for the build status
[14:16:56.721] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:56.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:47:0) after 1m0s
[14:17:56.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:47:0)
[14:17:56.739] Timer tick, asking Buildbucket for the build status
[14:17:56.842] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:56.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:48:0) after 1m0s
[14:18:56.861] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:48:0)
[14:18:56.861] Timer tick, asking Buildbucket for the build status
[14:18:56.908] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:56.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:49:0) after 1m0s
[14:19:56.932] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:49:0)
[14:19:56.932] Timer tick, asking Buildbucket for the build status
[14:19:57.367] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:57.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:50:0) after 1m0s
[14:20:57.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:50:0)
[14:20:57.385] Timer tick, asking Buildbucket for the build status
[14:20:57.435] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:57.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:51:0) after 1m0s
[14:21:57.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:51:0)
[14:21:57.423] Timer tick, asking Buildbucket for the build status
[14:21:57.470] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:57.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:52:0) after 1m0s
[14:22:57.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:52:0)
[14:22:57.487] Timer tick, asking Buildbucket for the build status
[14:22:57.587] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:57.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:53:0) after 1m0s
[14:23:57.652] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:53:0)
[14:23:57.652] Timer tick, asking Buildbucket for the build status
[14:23:57.736] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:57.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:54:0) after 1m0s
[14:24:57.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:54:0)
[14:24:57.755] Timer tick, asking Buildbucket for the build status
[14:24:57.803] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:57.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:55:0) after 1m0s
[14:25:57.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:55:0)
[14:25:57.790] Timer tick, asking Buildbucket for the build status
[14:25:57.838] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:57.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:56:0) after 1m0s
[14:26:57.855] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:56:0)
[14:26:57.855] Timer tick, asking Buildbucket for the build status
[14:26:57.991] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:57.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:57:0) after 1m0s
[14:27:58.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:57:0)
[14:27:58.027] Timer tick, asking Buildbucket for the build status
[14:27:58.122] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:58.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:58:0) after 1m0s
[14:28:58.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:58:0)
[14:28:58.129] Timer tick, asking Buildbucket for the build status
[14:28:58.192] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:58.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:59:0) after 1m0s
[14:29:58.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:59:0)
[14:29:58.215] Timer tick, asking Buildbucket for the build status
[14:29:58.304] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:58.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:60:0) after 1m0s
[14:30:58.324] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:60:0)
[14:30:58.324] Timer tick, asking Buildbucket for the build status
[14:30:58.390] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:58.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:61:0) after 1m0s
[14:31:58.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:61:0)
[14:31:58.427] Timer tick, asking Buildbucket for the build status
[14:31:58.504] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:58.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:62:0) after 1m0s
[14:32:58.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:62:0)
[14:32:58.514] Timer tick, asking Buildbucket for the build status
[14:32:58.589] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:58.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:63:0) after 1m0s
[14:33:58.624] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:63:0)
[14:33:58.624] Timer tick, asking Buildbucket for the build status
[14:33:59.587] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:59.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:64:0) after 1m0s
[14:34:59.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:64:0)
[14:34:59.587] Timer tick, asking Buildbucket for the build status
[14:34:59.712] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:59.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:65:0) after 1m0s
[14:35:59.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:65:0)
[14:35:59.742] Timer tick, asking Buildbucket for the build status
[14:35:59.972] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:59.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:66:0) after 1m0s
[14:37:00.003] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:66:0)
[14:37:00.003] Timer tick, asking Buildbucket for the build status
[14:37:00.062] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:00.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:67:0) after 1m0s
[14:38:00.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:67:0)
[14:38:00.086] Timer tick, asking Buildbucket for the build status
[14:38:00.469] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:00.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:68:0) after 1m0s
[14:39:00.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:68:0)
[14:39:00.491] Timer tick, asking Buildbucket for the build status
[14:39:00.553] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:00.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:69:0) after 1m0s
[14:40:00.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:69:0)
[14:40:00.571] Timer tick, asking Buildbucket for the build status
[14:40:00.628] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:00.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:70:0) after 1m0s
[14:41:00.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:70:0)
[14:41:00.666] Timer tick, asking Buildbucket for the build status
[14:41:00.782] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:00.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:71:0) after 1m0s
[14:42:00.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:71:0)
[14:42:00.799] Timer tick, asking Buildbucket for the build status
[14:42:00.877] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:00.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:72:0) after 1m0s
[14:43:00.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:72:0)
[14:43:00.899] Timer tick, asking Buildbucket for the build status
[14:43:01.084] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:01.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:73:0) after 1m0s
[14:44:01.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:73:0)
[14:44:01.111] Timer tick, asking Buildbucket for the build status
[14:44:01.523] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:01.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:74:0) after 1m0s
[14:45:01.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:74:0)
[14:45:01.542] Timer tick, asking Buildbucket for the build status
[14:45:01.613] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:01.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:75:0) after 1m0s
[14:46:01.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:75:0)
[14:46:01.601] Timer tick, asking Buildbucket for the build status
[14:46:01.682] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:01.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:76:0) after 1m0s
[14:47:01.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:76:0)
[14:47:01.676] Timer tick, asking Buildbucket for the build status
[14:47:01.743] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:01.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:77:0) after 1m0s
[14:48:01.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:77:0)
[14:48:01.771] Timer tick, asking Buildbucket for the build status
[14:48:02.083] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:02.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:78:0) after 1m0s
[14:49:02.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:78:0)
[14:49:02.068] Timer tick, asking Buildbucket for the build status
[14:49:02.112] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:02.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:79:0) after 1m0s
[14:50:02.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:79:0)
[14:50:02.115] Timer tick, asking Buildbucket for the build status
[14:50:02.193] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:02.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:80:0) after 1m0s
[14:51:02.187] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:80:0)
[14:51:02.187] Timer tick, asking Buildbucket for the build status
[14:51:02.233] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:02.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:81:0) after 1m0s
[14:52:02.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:81:0)
[14:52:02.246] Timer tick, asking Buildbucket for the build status
[14:52:02.521] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:02.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:82:0) after 1m0s
[14:53:02.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:82:0)
[14:53:02.572] Timer tick, asking Buildbucket for the build status
[14:53:02.619] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:02.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:83:0) after 1m0s
[14:54:02.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:83:0)
[14:54:02.617] Timer tick, asking Buildbucket for the build status
[14:54:02.768] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:02.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:84:0) after 1m0s
[14:55:02.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:84:0)
[14:55:02.875] Timer tick, asking Buildbucket for the build status
[14:55:02.935] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:02.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:85:0) after 1m0s
[14:56:02.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:85:0)
[14:56:02.928] Timer tick, asking Buildbucket for the build status
[14:56:02.992] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:02.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:86:0) after 1m0s
[14:57:03.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:86:0)
[14:57:03.015] Timer tick, asking Buildbucket for the build status
[14:57:03.198] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:03.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:87:0) after 1m0s
[14:58:03.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:87:0)
[14:58:03.199] Timer tick, asking Buildbucket for the build status
[14:58:03.296] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:03.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:88:0) after 1m0s
[14:59:03.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:88:0)
[14:59:03.314] Timer tick, asking Buildbucket for the build status
[14:59:03.360] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:03.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:89:0) after 1m0s
[15:00:03.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:89:0)
[15:00:03.392] Timer tick, asking Buildbucket for the build status
[15:00:03.442] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:03.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:90:0) after 1m0s
[15:01:03.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:90:0)
[15:01:03.454] Timer tick, asking Buildbucket for the build status
[15:01:03.610] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:03.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:91:0) after 1m0s
[15:02:03.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:91:0)
[15:02:03.598] Timer tick, asking Buildbucket for the build status
[15:02:03.668] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:03.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:92:0) after 1m0s
[15:03:03.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:92:0)
[15:03:03.689] Timer tick, asking Buildbucket for the build status
[15:03:03.837] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:03.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:93:0) after 1m0s
[15:04:03.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:93:0)
[15:04:03.914] Timer tick, asking Buildbucket for the build status
[15:04:04.026] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:04.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:94:0) after 1m0s
[15:05:04.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:94:0)
[15:05:04.152] Timer tick, asking Buildbucket for the build status
[15:05:04.304] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:04.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:95:0) after 1m0s
[15:06:04.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:95:0)
[15:06:04.326] Timer tick, asking Buildbucket for the build status
[15:06:04.377] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:04.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:96:0) after 1m0s
[15:07:04.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:96:0)
[15:07:04.426] Timer tick, asking Buildbucket for the build status
[15:07:04.486] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:04.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:97:0) after 1m0s
[15:08:04.570] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:97:0)
[15:08:04.570] Timer tick, asking Buildbucket for the build status
[15:08:04.633] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:04.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:98:0) after 1m0s
[15:09:04.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:98:0)
[15:09:04.688] Timer tick, asking Buildbucket for the build status
[15:09:04.741] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:04.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:99:0) after 1m0s
[15:10:04.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:99:0)
[15:10:04.800] Timer tick, asking Buildbucket for the build status
[15:10:04.851] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:04.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:100:0) after 1m0s
[15:11:04.872] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:100:0)
[15:11:04.872] Timer tick, asking Buildbucket for the build status
[15:11:04.992] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:04.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:101:0) after 1m0s
[15:12:05.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:101:0)
[15:12:05.009] Timer tick, asking Buildbucket for the build status
[15:12:05.056] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:05.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:102:0) after 1m0s
[15:13:05.070] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:102:0)
[15:13:05.070] Timer tick, asking Buildbucket for the build status
[15:13:05.141] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:05.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:103:0) after 1m0s
[15:14:05.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:103:0)
[15:14:05.148] Timer tick, asking Buildbucket for the build status
[15:14:05.212] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:05.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:104:0) after 1m0s
[15:15:05.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:104:0)
[15:15:05.214] Timer tick, asking Buildbucket for the build status
[15:15:05.319] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:05.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:105:0) after 1m0s
[15:16:05.350] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:105:0)
[15:16:05.350] Timer tick, asking Buildbucket for the build status
[15:16:05.405] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:05.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:106:0) after 1m0s
[15:17:05.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:106:0)
[15:17:05.489] Timer tick, asking Buildbucket for the build status
[15:17:05.650] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:05.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:107:0) after 1m0s
[15:18:05.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:107:0)
[15:18:05.679] Timer tick, asking Buildbucket for the build status
[15:18:05.787] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:05.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:108:0) after 1m0s
[15:19:05.814] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:108:0)
[15:19:05.814] Timer tick, asking Buildbucket for the build status
[15:19:05.867] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:05.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:109:0) after 1m0s
[15:20:05.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:109:0)
[15:20:05.889] Timer tick, asking Buildbucket for the build status
[15:20:06.091] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:06.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:110:0) after 1m0s
[15:21:06.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:110:0)
[15:21:06.098] Timer tick, asking Buildbucket for the build status
[15:21:06.154] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:06.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:111:0) after 1m0s
[15:22:06.176] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:111:0)
[15:22:06.176] Timer tick, asking Buildbucket for the build status
[15:22:06.291] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:06.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:112:0) after 1m0s
[15:23:06.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:112:0)
[15:23:06.308] Timer tick, asking Buildbucket for the build status
[15:23:06.421] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:06.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:113:0) after 1m0s
[15:24:06.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:113:0)
[15:24:06.421] Timer tick, asking Buildbucket for the build status
[15:24:06.567] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:06.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:114:0) after 1m0s
[15:25:06.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:114:0)
[15:25:06.587] Timer tick, asking Buildbucket for the build status
[15:25:06.639] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:06.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:115:0) after 1m0s
[15:26:06.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:115:0)
[15:26:06.618] Timer tick, asking Buildbucket for the build status
[15:26:06.671] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:06.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:116:0) after 1m0s
[15:27:06.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:116:0)
[15:27:06.640] Timer tick, asking Buildbucket for the build status
[15:27:06.774] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:06.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:117:0) after 1m0s
[15:28:06.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:117:0)
[15:28:06.813] Timer tick, asking Buildbucket for the build status
[15:28:06.913] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:06.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:118:0) after 1m0s
[15:29:06.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:118:0)
[15:29:06.967] Timer tick, asking Buildbucket for the build status
[15:29:07.016] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:07.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:119:0) after 1m0s
[15:30:07.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:119:0)
[15:30:07.028] Timer tick, asking Buildbucket for the build status
[15:30:07.087] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:07.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:120:0) after 1m0s
[15:31:07.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:120:0)
[15:31:07.086] Timer tick, asking Buildbucket for the build status
[15:31:07.132] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:07.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:121:0) after 1m0s
[15:32:07.170] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:121:0)
[15:32:07.170] Timer tick, asking Buildbucket for the build status
[15:32:07.229] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:07.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:122:0) after 1m0s
[15:33:07.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:122:0)
[15:33:07.451] Timer tick, asking Buildbucket for the build status
[15:33:07.492] Build 8919639866771784576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:07.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085069929007131824:123:0) after 1m0s
[15:33:50.014] Received PubSub notification, asking Buildbucket for the build status
[15:33:50.118] Build 8919639866771784576: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:33:50.118] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1551972828843464",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551965569674039",
"failure_reason": "BUILD_FAILURE",
"id": "8919639866771784576",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"16d98c206b75080d5057ec639c92a460c8be9456\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"image\": [\"fuchsia-debian-9-drawfork-v20181107-ee01d6c\"], \"kvm\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4154-750e250\"], \"machine_type\": [\"n1-standard-1\"], \"gpu\": [\"none\"], \"cores\": [\"1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"id\": [\"fuchsia-debian-1-03c1d9de-us-central1-c-bkkk\"], \"pool\": [\"luci.fuchsia.prod\"]}}, \"ui\": {\"info\": \"Failure buildbucket.run\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"recipe\": \"buildbucket_trigger\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"timeout\": 7200.0, \"path_config\": \"generic\", \"revision\": \"16d98c206b75080d5057ec639c92a460c8be9456\", \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-03c1d9de-us-central1-c-bkkk\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1551965593482485",
"status": "COMPLETED",
"status_changed_ts": "1551972829521729",
"tags": [
"builder:clang",
"buildset:commit/git/16d98c206b75080d5057ec639c92a460c8be9456",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/16d98c206b75080d5057ec639c92a460c8be9456",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085069929007131824",
"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/8919639866771784576/+/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:43712ccd010ad910",
"user_agent:luci-scheduler"
],
"updated_ts": "1551972829523041",
"url": "https://ci.chromium.org/b/8919639866771784576",
"utcnow_ts": "1551972830103812"
}
}
[15:33:50.118] Invocation finished in 2h1m1.959124894s with status FAILED