[00:55:01.028] New invocation is queued and will start shortly
[00:55:02.104] Starting the invocation (attempt 1)
[00:55:02.148] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:55:02.178] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:55:02.178] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9084936410838370160",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"7ffcd984c4de54dea96049b9cb6a74c9e9b84c1e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9084936410838370160",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/7ffcd984c4de54dea96049b9cb6a74c9e9b84c1e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7ffcd984c4de54dea96049b9cb6a74c9e9b84c1e",
"gitiles_ref:refs/heads/master"
]
}
[00:55:03.288] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552092902258219",
"id": "8919506348879823184",
"lease_expiration_ts": "1552218902972879",
"lease_key": "1164397496",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7ffcd984c4de54dea96049b9cb6a74c9e9b84c1e\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552092903110508",
"tags": [
"builder:clang",
"buildset:commit/git/7ffcd984c4de54dea96049b9cb6a74c9e9b84c1e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7ffcd984c4de54dea96049b9cb6a74c9e9b84c1e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084936410838370160",
"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/8919506348879823184/+/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:4378c3bd67f79a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552092903110620",
"url": "https://ci.chromium.org/b/8919506348879823184",
"utcnow_ts": "1552092903275474"
}
}
[00:55:03.288] Task URL: https://ci.chromium.org/b/8919506348879823184
[00:55:03.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:2:0) after 1m0s
[00:55:14.074] Received PubSub notification, asking Buildbucket for the build status
[00:55:14.133] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:03.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:2:0)
[00:56:03.321] Timer tick, asking Buildbucket for the build status
[00:56:03.387] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:03.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:4:0) after 1m0s
[00:57:03.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:4:0)
[00:57:03.403] Timer tick, asking Buildbucket for the build status
[00:57:03.483] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:03.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:5:0) after 1m0s
[00:58:03.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:5:0)
[00:58:03.478] Timer tick, asking Buildbucket for the build status
[00:58:03.532] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:03.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:6:0) after 1m0s
[00:59:03.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:6:0)
[00:59:03.579] Timer tick, asking Buildbucket for the build status
[00:59:03.632] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:03.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:7:0) after 1m0s
[01:00:03.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:7:0)
[01:00:03.619] Timer tick, asking Buildbucket for the build status
[01:00:03.999] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:03.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:8:0) after 1m0s
[01:01:04.050] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:8:0)
[01:01:04.050] Timer tick, asking Buildbucket for the build status
[01:01:04.113] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:04.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:9:0) after 1m0s
[01:02:04.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:9:0)
[01:02:04.133] Timer tick, asking Buildbucket for the build status
[01:02:04.213] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:04.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:10:0) after 1m0s
[01:03:04.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:10:0)
[01:03:04.225] Timer tick, asking Buildbucket for the build status
[01:03:04.295] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:04.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:11:0) after 1m0s
[01:04:04.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:11:0)
[01:04:04.340] Timer tick, asking Buildbucket for the build status
[01:04:04.433] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:04.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:12:0) after 1m0s
[01:05:04.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:12:0)
[01:05:04.464] Timer tick, asking Buildbucket for the build status
[01:05:04.679] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:04.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:13:0) after 1m0s
[01:06:04.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:13:0)
[01:06:04.696] Timer tick, asking Buildbucket for the build status
[01:06:04.811] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:04.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:14:0) after 1m0s
[01:07:04.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:14:0)
[01:07:04.781] Timer tick, asking Buildbucket for the build status
[01:07:04.890] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:04.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:15:0) after 1m0s
[01:08:04.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:15:0)
[01:08:04.902] Timer tick, asking Buildbucket for the build status
[01:08:04.969] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:04.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:16:0) after 1m0s
[01:09:04.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:16:0)
[01:09:04.950] Timer tick, asking Buildbucket for the build status
[01:09:05.032] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:05.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:17:0) after 1m0s
[01:10:05.052] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:17:0)
[01:10:05.052] Timer tick, asking Buildbucket for the build status
[01:10:05.355] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:05.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:18:0) after 1m0s
[01:11:05.370] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:18:0)
[01:11:05.370] Timer tick, asking Buildbucket for the build status
[01:11:05.582] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:05.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:19:0) after 1m0s
[01:12:05.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:19:0)
[01:12:05.593] Timer tick, asking Buildbucket for the build status
[01:12:05.679] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:05.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:20:0) after 1m0s
[01:13:05.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:20:0)
[01:13:05.673] Timer tick, asking Buildbucket for the build status
[01:13:05.715] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:05.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:21:0) after 1m0s
[01:14:05.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:21:0)
[01:14:05.725] Timer tick, asking Buildbucket for the build status
[01:14:05.808] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:05.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:22:0) after 1m0s
[01:15:05.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:22:0)
[01:15:05.800] Timer tick, asking Buildbucket for the build status
[01:15:06.064] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:06.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:23:0) after 1m0s
[01:16:06.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:23:0)
[01:16:06.069] Timer tick, asking Buildbucket for the build status
[01:16:06.247] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:06.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:24:0) after 1m0s
[01:17:06.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:24:0)
[01:17:06.271] Timer tick, asking Buildbucket for the build status
[01:17:06.321] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:06.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:25:0) after 1m0s
[01:18:06.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:25:0)
[01:18:06.309] Timer tick, asking Buildbucket for the build status
[01:18:06.484] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:06.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:26:0) after 1m0s
[01:19:06.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:26:0)
[01:19:06.506] Timer tick, asking Buildbucket for the build status
[01:19:06.818] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:06.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:27:0) after 1m0s
[01:20:06.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:27:0)
[01:20:06.837] Timer tick, asking Buildbucket for the build status
[01:20:07.028] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:07.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:28:0) after 1m0s
[01:21:07.039] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:28:0)
[01:21:07.039] Timer tick, asking Buildbucket for the build status
[01:21:07.115] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:07.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:29:0) after 1m0s
[01:22:07.141] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:29:0)
[01:22:07.141] Timer tick, asking Buildbucket for the build status
[01:22:07.350] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:07.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:30:0) after 1m0s
[01:23:07.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:30:0)
[01:23:07.336] Timer tick, asking Buildbucket for the build status
[01:23:07.382] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:07.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:31:0) after 1m0s
[01:24:07.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:31:0)
[01:24:07.394] Timer tick, asking Buildbucket for the build status
[01:24:07.501] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:07.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:32:0) after 1m0s
[01:25:07.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:32:0)
[01:25:07.521] Timer tick, asking Buildbucket for the build status
[01:25:07.625] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:07.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:33:0) after 1m0s
[01:26:07.647] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:33:0)
[01:26:07.647] Timer tick, asking Buildbucket for the build status
[01:26:07.712] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:07.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:34:0) after 1m0s
[01:27:07.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:34:0)
[01:27:07.732] Timer tick, asking Buildbucket for the build status
[01:27:07.928] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:07.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:35:0) after 1m0s
[01:28:07.935] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:35:0)
[01:28:07.935] Timer tick, asking Buildbucket for the build status
[01:28:07.980] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:07.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:36:0) after 1m0s
[01:29:07.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:36:0)
[01:29:07.957] Timer tick, asking Buildbucket for the build status
[01:29:08.038] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:08.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:37:0) after 1m0s
[01:30:08.058] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:37:0)
[01:30:08.058] Timer tick, asking Buildbucket for the build status
[01:30:08.123] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:08.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:38:0) after 1m0s
[01:31:08.142] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:38:0)
[01:31:08.142] Timer tick, asking Buildbucket for the build status
[01:31:08.206] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:08.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:39:0) after 1m0s
[01:32:08.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:39:0)
[01:32:08.207] Timer tick, asking Buildbucket for the build status
[01:32:08.375] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:08.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:40:0) after 1m0s
[01:33:08.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:40:0)
[01:33:08.393] Timer tick, asking Buildbucket for the build status
[01:33:08.458] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:08.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:41:0) after 1m0s
[01:34:08.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:41:0)
[01:34:08.470] Timer tick, asking Buildbucket for the build status
[01:34:08.659] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:08.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:42:0) after 1m0s
[01:35:08.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:42:0)
[01:35:08.697] Timer tick, asking Buildbucket for the build status
[01:35:08.804] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:08.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:43:0) after 1m0s
[01:36:08.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:43:0)
[01:36:08.793] Timer tick, asking Buildbucket for the build status
[01:36:08.844] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:08.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:44:0) after 1m0s
[01:37:08.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:44:0)
[01:37:08.870] Timer tick, asking Buildbucket for the build status
[01:37:09.026] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:09.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:45:0) after 1m0s
[01:38:09.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:45:0)
[01:38:09.047] Timer tick, asking Buildbucket for the build status
[01:38:09.174] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:09.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:46:0) after 1m0s
[01:39:09.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:46:0)
[01:39:09.168] Timer tick, asking Buildbucket for the build status
[01:39:09.253] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:09.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:47:0) after 1m0s
[01:40:09.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:47:0)
[01:40:09.277] Timer tick, asking Buildbucket for the build status
[01:40:09.342] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:09.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:48:0) after 1m0s
[01:41:09.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:48:0)
[01:41:09.318] Timer tick, asking Buildbucket for the build status
[01:41:09.626] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:09.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:49:0) after 1m0s
[01:42:09.660] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:49:0)
[01:42:09.660] Timer tick, asking Buildbucket for the build status
[01:42:09.711] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:09.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:50:0) after 1m0s
[01:43:09.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:50:0)
[01:43:09.715] Timer tick, asking Buildbucket for the build status
[01:43:09.788] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:09.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:51:0) after 1m0s
[01:44:09.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:51:0)
[01:44:09.815] Timer tick, asking Buildbucket for the build status
[01:44:09.902] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:09.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:52:0) after 1m0s
[01:45:09.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:52:0)
[01:45:09.902] Timer tick, asking Buildbucket for the build status
[01:45:09.992] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:09.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:53:0) after 1m0s
[01:46:10.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:53:0)
[01:46:10.013] Timer tick, asking Buildbucket for the build status
[01:46:10.087] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:10.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:54:0) after 1m0s
[01:47:10.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:54:0)
[01:47:10.068] Timer tick, asking Buildbucket for the build status
[01:47:10.113] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:10.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:55:0) after 1m0s
[01:48:10.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:55:0)
[01:48:10.090] Timer tick, asking Buildbucket for the build status
[01:48:10.165] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:10.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:56:0) after 1m0s
[01:49:10.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:56:0)
[01:49:10.153] Timer tick, asking Buildbucket for the build status
[01:49:10.289] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:10.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:57:0) after 1m0s
[01:50:10.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:57:0)
[01:50:10.319] Timer tick, asking Buildbucket for the build status
[01:50:10.494] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:10.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:58:0) after 1m0s
[01:51:10.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:58:0)
[01:51:10.513] Timer tick, asking Buildbucket for the build status
[01:51:10.599] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:10.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:59:0) after 1m0s
[01:52:10.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:59:0)
[01:52:10.596] Timer tick, asking Buildbucket for the build status
[01:52:10.762] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:10.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:60:0) after 1m0s
[01:53:10.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:60:0)
[01:53:10.830] Timer tick, asking Buildbucket for the build status
[01:53:10.879] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:10.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:61:0) after 1m0s
[01:54:10.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:61:0)
[01:54:10.927] Timer tick, asking Buildbucket for the build status
[01:54:11.014] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:11.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:62:0) after 1m0s
[01:55:11.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:62:0)
[01:55:11.008] Timer tick, asking Buildbucket for the build status
[01:55:11.196] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:11.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:63:0) after 1m0s
[01:56:11.262] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:63:0)
[01:56:11.262] Timer tick, asking Buildbucket for the build status
[01:56:11.441] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:11.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:64:0) after 1m0s
[01:57:11.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:64:0)
[01:57:11.430] Timer tick, asking Buildbucket for the build status
[01:57:11.610] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:11.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:65:0) after 1m0s
[01:58:11.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:65:0)
[01:58:11.594] Timer tick, asking Buildbucket for the build status
[01:58:11.664] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:11.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:66:0) after 1m0s
[01:59:11.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:66:0)
[01:59:11.656] Timer tick, asking Buildbucket for the build status
[01:59:11.716] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:11.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:67:0) after 1m0s
[02:00:11.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:67:0)
[02:00:11.734] Timer tick, asking Buildbucket for the build status
[02:00:11.871] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:11.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:68:0) after 1m0s
[02:01:11.891] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:68:0)
[02:01:11.891] Timer tick, asking Buildbucket for the build status
[02:01:12.126] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:12.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:69:0) after 1m0s
[02:02:12.140] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:69:0)
[02:02:12.141] Timer tick, asking Buildbucket for the build status
[02:02:12.289] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:12.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:70:0) after 1m0s
[02:03:12.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:70:0)
[02:03:12.281] Timer tick, asking Buildbucket for the build status
[02:03:12.388] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:12.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:71:0) after 1m0s
[02:04:12.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:71:0)
[02:04:12.407] Timer tick, asking Buildbucket for the build status
[02:04:12.519] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:12.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:72:0) after 1m0s
[02:05:12.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:72:0)
[02:05:12.853] Timer tick, asking Buildbucket for the build status
[02:05:12.903] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:12.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:73:0) after 1m0s
[02:06:12.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:73:0)
[02:06:12.980] Timer tick, asking Buildbucket for the build status
[02:06:13.026] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:13.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:74:0) after 1m0s
[02:07:13.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:74:0)
[02:07:13.064] Timer tick, asking Buildbucket for the build status
[02:07:13.118] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:13.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:75:0) after 1m0s
[02:08:13.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:75:0)
[02:08:13.099] Timer tick, asking Buildbucket for the build status
[02:08:13.162] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:13.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:76:0) after 1m0s
[02:09:13.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:76:0)
[02:09:13.174] Timer tick, asking Buildbucket for the build status
[02:09:13.296] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:13.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:77:0) after 1m0s
[02:10:13.288] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:77:0)
[02:10:13.288] Timer tick, asking Buildbucket for the build status
[02:10:13.330] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:13.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:78:0) after 1m0s
[02:11:13.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:78:0)
[02:11:13.317] Timer tick, asking Buildbucket for the build status
[02:11:13.403] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:13.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:79:0) after 1m0s
[02:12:13.424] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:79:0)
[02:12:13.424] Timer tick, asking Buildbucket for the build status
[02:12:13.479] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:13.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:80:0) after 1m0s
[02:13:13.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:80:0)
[02:13:13.534] Timer tick, asking Buildbucket for the build status
[02:13:13.599] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:13.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:81:0) after 1m0s
[02:14:13.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:81:0)
[02:14:13.620] Timer tick, asking Buildbucket for the build status
[02:14:13.769] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:13.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:82:0) after 1m0s
[02:15:13.763] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:82:0)
[02:15:13.763] Timer tick, asking Buildbucket for the build status
[02:15:13.924] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:13.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:83:0) after 1m0s
[02:16:13.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:83:0)
[02:16:13.936] Timer tick, asking Buildbucket for the build status
[02:16:14.002] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:14.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:84:0) after 1m0s
[02:17:14.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:84:0)
[02:17:14.027] Timer tick, asking Buildbucket for the build status
[02:17:14.078] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:14.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:85:0) after 1m0s
[02:18:14.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:85:0)
[02:18:14.093] Timer tick, asking Buildbucket for the build status
[02:18:14.164] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:14.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:86:0) after 1m0s
[02:19:14.165] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:86:0)
[02:19:14.165] Timer tick, asking Buildbucket for the build status
[02:19:14.309] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:14.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:87:0) after 1m0s
[02:20:14.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:87:0)
[02:20:14.315] Timer tick, asking Buildbucket for the build status
[02:20:14.379] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:14.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:88:0) after 1m0s
[02:21:14.409] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:88:0)
[02:21:14.409] Timer tick, asking Buildbucket for the build status
[02:21:14.453] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:14.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:89:0) after 1m0s
[02:22:14.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:89:0)
[02:22:14.538] Timer tick, asking Buildbucket for the build status
[02:22:14.680] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:14.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:90:0) after 1m0s
[02:23:14.655] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:90:0)
[02:23:14.655] Timer tick, asking Buildbucket for the build status
[02:23:14.702] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:14.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:91:0) after 1m0s
[02:24:14.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:91:0)
[02:24:14.784] Timer tick, asking Buildbucket for the build status
[02:24:15.101] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:15.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:92:0) after 1m0s
[02:25:15.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:92:0)
[02:25:15.072] Timer tick, asking Buildbucket for the build status
[02:25:15.127] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:15.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:93:0) after 1m0s
[02:26:15.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:93:0)
[02:26:15.145] Timer tick, asking Buildbucket for the build status
[02:26:15.191] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:15.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:94:0) after 1m0s
[02:27:15.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:94:0)
[02:27:15.183] Timer tick, asking Buildbucket for the build status
[02:27:15.287] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:15.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:95:0) after 1m0s
[02:28:15.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:95:0)
[02:28:15.309] Timer tick, asking Buildbucket for the build status
[02:28:15.518] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:15.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:96:0) after 1m0s
[02:29:15.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:96:0)
[02:29:15.539] Timer tick, asking Buildbucket for the build status
[02:29:15.609] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:15.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:97:0) after 1m0s
[02:30:15.631] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:97:0)
[02:30:15.631] Timer tick, asking Buildbucket for the build status
[02:30:15.770] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:15.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:98:0) after 1m0s
[02:31:15.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:98:0)
[02:31:15.756] Timer tick, asking Buildbucket for the build status
[02:31:15.804] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:15.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:99:0) after 1m0s
[02:32:15.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:99:0)
[02:32:15.801] Timer tick, asking Buildbucket for the build status
[02:32:15.854] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:15.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:100:0) after 1m0s
[02:33:15.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:100:0)
[02:33:15.876] Timer tick, asking Buildbucket for the build status
[02:33:15.998] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:15.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:101:0) after 1m0s
[02:34:15.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:101:0)
[02:34:15.985] Timer tick, asking Buildbucket for the build status
[02:34:16.071] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:16.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:102:0) after 1m0s
[02:35:16.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:102:0)
[02:35:16.094] Timer tick, asking Buildbucket for the build status
[02:35:16.226] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:16.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:103:0) after 1m0s
[02:36:16.227] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:103:0)
[02:36:16.227] Timer tick, asking Buildbucket for the build status
[02:36:16.399] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:16.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:104:0) after 1m0s
[02:37:16.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:104:0)
[02:37:16.420] Timer tick, asking Buildbucket for the build status
[02:37:16.473] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:16.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:105:0) after 1m0s
[02:38:16.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:105:0)
[02:38:16.481] Timer tick, asking Buildbucket for the build status
[02:38:16.587] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:16.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:106:0) after 1m0s
[02:39:16.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:106:0)
[02:39:16.594] Timer tick, asking Buildbucket for the build status
[02:39:16.787] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:16.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:107:0) after 1m0s
[02:40:16.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:107:0)
[02:40:16.807] Timer tick, asking Buildbucket for the build status
[02:40:16.881] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:16.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:108:0) after 1m0s
[02:41:16.855] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:108:0)
[02:41:16.855] Timer tick, asking Buildbucket for the build status
[02:41:17.083] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:17.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:109:0) after 1m0s
[02:42:17.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:109:0)
[02:42:17.113] Timer tick, asking Buildbucket for the build status
[02:42:17.195] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:17.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:110:0) after 1m0s
[02:43:17.195] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:110:0)
[02:43:17.195] Timer tick, asking Buildbucket for the build status
[02:43:17.353] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:17.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:111:0) after 1m0s
[02:44:17.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:111:0)
[02:44:17.342] Timer tick, asking Buildbucket for the build status
[02:44:17.503] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:17.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:112:0) after 1m0s
[02:45:17.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:112:0)
[02:45:17.513] Timer tick, asking Buildbucket for the build status
[02:45:17.563] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:17.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:113:0) after 1m0s
[02:46:17.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:113:0)
[02:46:17.603] Timer tick, asking Buildbucket for the build status
[02:46:17.673] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:17.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:114:0) after 1m0s
[02:47:17.658] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:114:0)
[02:47:17.658] Timer tick, asking Buildbucket for the build status
[02:47:17.760] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:17.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:115:0) after 1m0s
[02:48:17.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:115:0)
[02:48:17.777] Timer tick, asking Buildbucket for the build status
[02:48:17.957] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:17.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:116:0) after 1m0s
[02:49:18.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:116:0)
[02:49:18.008] Timer tick, asking Buildbucket for the build status
[02:49:18.066] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:18.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:117:0) after 1m0s
[02:50:18.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:117:0)
[02:50:18.086] Timer tick, asking Buildbucket for the build status
[02:50:18.196] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:18.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:118:0) after 1m0s
[02:51:18.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:118:0)
[02:51:18.209] Timer tick, asking Buildbucket for the build status
[02:51:18.591] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:18.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:119:0) after 1m0s
[02:52:18.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:119:0)
[02:52:18.625] Timer tick, asking Buildbucket for the build status
[02:52:18.704] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:18.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:120:0) after 1m0s
[02:53:18.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:120:0)
[02:53:18.725] Timer tick, asking Buildbucket for the build status
[02:53:19.086] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:19.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:121:0) after 1m0s
[02:54:19.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:121:0)
[02:54:19.115] Timer tick, asking Buildbucket for the build status
[02:54:19.228] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:19.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:122:0) after 1m0s
[02:55:19.250] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:122:0)
[02:55:19.250] Timer tick, asking Buildbucket for the build status
[02:55:19.463] Build 8919506348879823184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:19.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084936410838370160:123:0) after 1m0s
[02:55:43.225] Received PubSub notification, asking Buildbucket for the build status
[02:55:43.284] Build 8919506348879823184: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:55:43.284] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1552100142372396",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552092902258219",
"failure_reason": "BUILD_FAILURE",
"id": "8919506348879823184",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7ffcd984c4de54dea96049b9cb6a74c9e9b84c1e\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"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\": [\"4158-1053c58\"], \"machine_type\": [\"n1-standard-1\"], \"gpu\": [\"none\"], \"cores\": [\"1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"id\": [\"fuchsia-debian-1-03c1d9de-us-central1-b-gk6n\"], \"pool\": [\"luci.fuchsia.prod\"], \"caches\": [\"vpython\"]}}, \"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\", \"bot_id\": \"fuchsia-debian-1-03c1d9de-us-central1-b-gk6n\", \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"revision\": \"7ffcd984c4de54dea96049b9cb6a74c9e9b84c1e\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1552092913302854",
"status": "COMPLETED",
"status_changed_ts": "1552100142972544",
"tags": [
"builder:clang",
"buildset:commit/git/7ffcd984c4de54dea96049b9cb6a74c9e9b84c1e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7ffcd984c4de54dea96049b9cb6a74c9e9b84c1e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084936410838370160",
"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/8919506348879823184/+/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:4378c3bd67f79a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552100142976461",
"url": "https://ci.chromium.org/b/8919506348879823184",
"utcnow_ts": "1552100143275720"
}
}
[02:55:43.284] Invocation finished in 2h0m42.276795589s with status FAILED