[06:55:18.108] New invocation is queued and will start shortly
[06:55:21.283] Starting the invocation (attempt 1)
[06:55:21.321] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:55:21.321] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:55:21.321] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080655686261756272",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"013503c78d7b648152a07022e75b98219aba2ba9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080655686261756272",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/013503c78d7b648152a07022e75b98219aba2ba9",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/013503c78d7b648152a07022e75b98219aba2ba9",
"gitiles_ref:refs/heads/master"
]
}
[06:55:21.913] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556175321421512",
"id": "8915225622123562048",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"013503c78d7b648152a07022e75b98219aba2ba9\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556175321821777",
"tags": [
"builder:clang",
"buildset:commit/git/013503c78d7b648152a07022e75b98219aba2ba9",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/013503c78d7b648152a07022e75b98219aba2ba9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080655686261756272",
"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/8915225622123562048/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:buildbucket_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1556175321821903",
"url": "https://ci.chromium.org/b/8915225622123562048",
"utcnow_ts": "1556175321903397"
}
}
[06:55:21.913] Task URL: https://ci.chromium.org/b/8915225622123562048
[06:55:21.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:2:0) after 1m0s
[06:55:41.449] Received PubSub notification, asking Buildbucket for the build status
[06:55:41.510] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:21.935] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:2:0)
[06:56:21.935] Timer tick, asking Buildbucket for the build status
[06:56:22.200] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:22.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:4:0) after 1m0s
[06:57:22.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:4:0)
[06:57:22.212] Timer tick, asking Buildbucket for the build status
[06:57:22.369] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:22.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:5:0) after 1m0s
[06:58:22.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:5:0)
[06:58:22.387] Timer tick, asking Buildbucket for the build status
[06:58:22.449] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:22.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:6:0) after 1m0s
[06:59:22.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:6:0)
[06:59:22.468] Timer tick, asking Buildbucket for the build status
[06:59:22.708] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:22.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:7:0) after 1m0s
[07:00:22.729] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:7:0)
[07:00:22.729] Timer tick, asking Buildbucket for the build status
[07:00:22.831] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:22.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:8:0) after 1m0s
[07:01:22.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:8:0)
[07:01:22.848] Timer tick, asking Buildbucket for the build status
[07:01:23.456] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:23.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:9:0) after 1m0s
[07:02:23.452] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:9:0)
[07:02:23.452] Timer tick, asking Buildbucket for the build status
[07:02:23.558] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:23.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:10:0) after 1m0s
[07:03:23.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:10:0)
[07:03:23.593] Timer tick, asking Buildbucket for the build status
[07:03:23.656] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:23.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:11:0) after 1m0s
[07:04:23.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:11:0)
[07:04:23.664] Timer tick, asking Buildbucket for the build status
[07:04:23.758] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:23.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:12:0) after 1m0s
[07:05:23.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:12:0)
[07:05:23.778] Timer tick, asking Buildbucket for the build status
[07:05:23.835] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:23.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:13:0) after 1m0s
[07:06:23.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:13:0)
[07:06:23.919] Timer tick, asking Buildbucket for the build status
[07:06:23.960] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:23.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:14:0) after 1m0s
[07:07:23.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:14:0)
[07:07:23.990] Timer tick, asking Buildbucket for the build status
[07:07:24.038] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:24.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:15:0) after 1m0s
[07:08:24.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:15:0)
[07:08:24.065] Timer tick, asking Buildbucket for the build status
[07:08:24.128] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:24.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:16:0) after 1m0s
[07:09:24.161] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:16:0)
[07:09:24.161] Timer tick, asking Buildbucket for the build status
[07:09:24.223] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:24.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:17:0) after 1m0s
[07:10:24.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:17:0)
[07:10:24.201] Timer tick, asking Buildbucket for the build status
[07:10:24.271] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:24.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:18:0) after 1m0s
[07:11:24.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:18:0)
[07:11:24.274] Timer tick, asking Buildbucket for the build status
[07:11:24.326] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:24.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:19:0) after 1m0s
[07:12:24.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:19:0)
[07:12:24.347] Timer tick, asking Buildbucket for the build status
[07:12:24.386] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:24.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:20:0) after 1m0s
[07:13:24.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:20:0)
[07:13:24.361] Timer tick, asking Buildbucket for the build status
[07:13:24.700] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:24.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:21:0) after 1m0s
[07:14:24.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:21:0)
[07:14:24.722] Timer tick, asking Buildbucket for the build status
[07:14:24.793] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:24.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:22:0) after 1m0s
[07:15:24.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:22:0)
[07:15:24.767] Timer tick, asking Buildbucket for the build status
[07:15:24.874] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:24.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:23:0) after 1m0s
[07:16:24.893] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:23:0)
[07:16:24.893] Timer tick, asking Buildbucket for the build status
[07:16:25.078] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:25.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:24:0) after 1m0s
[07:17:25.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:24:0)
[07:17:25.182] Timer tick, asking Buildbucket for the build status
[07:17:25.280] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:25.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:25:0) after 1m0s
[07:18:25.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:25:0)
[07:18:25.283] Timer tick, asking Buildbucket for the build status
[07:18:25.491] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:25.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:26:0) after 1m0s
[07:19:25.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:26:0)
[07:19:25.516] Timer tick, asking Buildbucket for the build status
[07:19:25.670] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:25.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:27:0) after 1m0s
[07:20:25.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:27:0)
[07:20:25.648] Timer tick, asking Buildbucket for the build status
[07:20:25.832] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:25.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:28:0) after 1m0s
[07:21:25.855] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:28:0)
[07:21:25.855] Timer tick, asking Buildbucket for the build status
[07:21:25.972] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:25.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:29:0) after 1m0s
[07:22:25.991] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:29:0)
[07:22:25.991] Timer tick, asking Buildbucket for the build status
[07:22:26.094] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:26.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:30:0) after 1m0s
[07:23:26.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:30:0)
[07:23:26.113] Timer tick, asking Buildbucket for the build status
[07:23:26.174] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:26.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:31:0) after 1m0s
[07:24:26.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:31:0)
[07:24:26.194] Timer tick, asking Buildbucket for the build status
[07:24:26.379] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:26.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:32:0) after 1m0s
[07:25:26.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:32:0)
[07:25:26.398] Timer tick, asking Buildbucket for the build status
[07:25:26.568] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:26.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:33:0) after 1m0s
[07:26:26.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:33:0)
[07:26:26.590] Timer tick, asking Buildbucket for the build status
[07:26:26.649] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:26.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:34:0) after 1m0s
[07:27:26.659] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:34:0)
[07:27:26.659] Timer tick, asking Buildbucket for the build status
[07:27:26.776] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:26.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:35:0) after 1m0s
[07:28:26.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:35:0)
[07:28:26.799] Timer tick, asking Buildbucket for the build status
[07:28:26.876] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:26.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:36:0) after 1m0s
[07:29:26.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:36:0)
[07:29:26.899] Timer tick, asking Buildbucket for the build status
[07:29:26.997] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:26.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:37:0) after 1m0s
[07:30:27.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:37:0)
[07:30:27.005] Timer tick, asking Buildbucket for the build status
[07:30:27.088] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:27.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:38:0) after 1m0s
[07:31:27.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:38:0)
[07:31:27.109] Timer tick, asking Buildbucket for the build status
[07:31:27.147] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:27.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:39:0) after 1m0s
[07:32:27.195] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:39:0)
[07:32:27.195] Timer tick, asking Buildbucket for the build status
[07:32:27.299] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:27.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:40:0) after 1m0s
[07:33:27.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:40:0)
[07:33:27.317] Timer tick, asking Buildbucket for the build status
[07:33:27.682] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:27.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:41:0) after 1m0s
[07:34:27.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:41:0)
[07:34:27.701] Timer tick, asking Buildbucket for the build status
[07:34:27.864] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:27.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:42:0) after 1m0s
[07:35:27.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:42:0)
[07:35:27.887] Timer tick, asking Buildbucket for the build status
[07:35:27.955] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:27.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:43:0) after 1m0s
[07:36:27.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:43:0)
[07:36:27.975] Timer tick, asking Buildbucket for the build status
[07:36:28.039] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:28.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:44:0) after 1m0s
[07:37:28.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:44:0)
[07:37:28.061] Timer tick, asking Buildbucket for the build status
[07:37:28.194] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:28.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:45:0) after 1m0s
[07:38:28.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:45:0)
[07:38:28.213] Timer tick, asking Buildbucket for the build status
[07:38:28.273] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:28.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:46:0) after 1m0s
[07:39:28.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:46:0)
[07:39:28.301] Timer tick, asking Buildbucket for the build status
[07:39:28.578] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:28.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:47:0) after 1m0s
[07:40:28.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:47:0)
[07:40:28.601] Timer tick, asking Buildbucket for the build status
[07:40:28.791] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:28.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:48:0) after 1m0s
[07:41:28.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:48:0)
[07:41:28.783] Timer tick, asking Buildbucket for the build status
[07:41:28.867] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:28.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:49:0) after 1m0s
[07:42:28.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:49:0)
[07:42:28.878] Timer tick, asking Buildbucket for the build status
[07:42:28.912] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:28.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:50:0) after 1m0s
[07:43:28.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:50:0)
[07:43:28.934] Timer tick, asking Buildbucket for the build status
[07:43:29.017] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:29.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:51:0) after 1m0s
[07:44:29.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:51:0)
[07:44:29.013] Timer tick, asking Buildbucket for the build status
[07:44:29.153] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:29.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:52:0) after 1m0s
[07:45:29.161] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:52:0)
[07:45:29.161] Timer tick, asking Buildbucket for the build status
[07:45:29.287] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:29.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:53:0) after 1m0s
[07:46:29.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:53:0)
[07:46:29.338] Timer tick, asking Buildbucket for the build status
[07:46:29.543] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:29.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:54:0) after 1m0s
[07:47:29.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:54:0)
[07:47:29.569] Timer tick, asking Buildbucket for the build status
[07:47:29.842] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:29.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:55:0) after 1m0s
[07:48:29.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:55:0)
[07:48:29.812] Timer tick, asking Buildbucket for the build status
[07:48:29.852] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:29.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:56:0) after 1m0s
[07:49:29.851] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:56:0)
[07:49:29.851] Timer tick, asking Buildbucket for the build status
[07:49:29.920] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:29.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:57:0) after 1m0s
[07:50:29.946] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:57:0)
[07:50:29.946] Timer tick, asking Buildbucket for the build status
[07:50:29.987] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:29.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:58:0) after 1m0s
[07:51:30.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:58:0)
[07:51:30.027] Timer tick, asking Buildbucket for the build status
[07:51:30.124] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:30.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:59:0) after 1m0s
[07:52:30.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:59:0)
[07:52:30.106] Timer tick, asking Buildbucket for the build status
[07:52:30.182] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:30.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:60:0) after 1m0s
[07:53:30.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:60:0)
[07:53:30.202] Timer tick, asking Buildbucket for the build status
[07:53:30.308] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:30.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:61:0) after 1m0s
[07:54:30.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:61:0)
[07:54:30.332] Timer tick, asking Buildbucket for the build status
[07:54:30.368] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:30.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:62:0) after 1m0s
[07:55:30.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:62:0)
[07:55:30.390] Timer tick, asking Buildbucket for the build status
[07:55:30.571] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:30.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:63:0) after 1m0s
[07:56:30.555] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:63:0)
[07:56:30.555] Timer tick, asking Buildbucket for the build status
[07:56:30.599] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:30.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:64:0) after 1m0s
[07:57:30.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:64:0)
[07:57:30.620] Timer tick, asking Buildbucket for the build status
[07:57:30.761] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:30.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:65:0) after 1m0s
[07:58:30.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:65:0)
[07:58:30.754] Timer tick, asking Buildbucket for the build status
[07:58:30.865] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:30.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:66:0) after 1m0s
[07:59:30.885] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:66:0)
[07:59:30.885] Timer tick, asking Buildbucket for the build status
[07:59:30.943] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:30.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:67:0) after 1m0s
[08:00:30.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:67:0)
[08:00:30.966] Timer tick, asking Buildbucket for the build status
[08:00:31.164] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:31.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:68:0) after 1m0s
[08:01:31.184] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:68:0)
[08:01:31.184] Timer tick, asking Buildbucket for the build status
[08:01:31.228] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:31.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:69:0) after 1m0s
[08:02:31.240] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:69:0)
[08:02:31.240] Timer tick, asking Buildbucket for the build status
[08:02:31.300] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:31.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:70:0) after 1m0s
[08:03:31.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:70:0)
[08:03:31.336] Timer tick, asking Buildbucket for the build status
[08:03:31.517] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:31.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:71:0) after 1m0s
[08:04:31.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:71:0)
[08:04:31.536] Timer tick, asking Buildbucket for the build status
[08:04:31.705] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:31.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:72:0) after 1m0s
[08:05:31.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:72:0)
[08:05:31.677] Timer tick, asking Buildbucket for the build status
[08:05:31.747] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:31.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:73:0) after 1m0s
[08:06:31.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:73:0)
[08:06:31.772] Timer tick, asking Buildbucket for the build status
[08:06:31.861] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:31.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:74:0) after 1m0s
[08:07:31.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:74:0)
[08:07:31.905] Timer tick, asking Buildbucket for the build status
[08:07:32.925] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:32.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:75:0) after 1m0s
[08:08:32.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:75:0)
[08:08:32.917] Timer tick, asking Buildbucket for the build status
[08:08:32.962] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:32.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:76:0) after 1m0s
[08:09:32.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:76:0)
[08:09:32.978] Timer tick, asking Buildbucket for the build status
[08:09:33.067] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:33.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:77:0) after 1m0s
[08:10:33.108] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:77:0)
[08:10:33.108] Timer tick, asking Buildbucket for the build status
[08:10:33.164] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:33.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:78:0) after 1m0s
[08:11:33.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:78:0)
[08:11:33.174] Timer tick, asking Buildbucket for the build status
[08:11:33.284] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:33.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:79:0) after 1m0s
[08:12:33.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:79:0)
[08:12:33.300] Timer tick, asking Buildbucket for the build status
[08:12:33.355] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:33.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:80:0) after 1m0s
[08:13:33.333] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:80:0)
[08:13:33.333] Timer tick, asking Buildbucket for the build status
[08:13:33.390] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:33.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:81:0) after 1m0s
[08:14:33.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:81:0)
[08:14:33.417] Timer tick, asking Buildbucket for the build status
[08:14:33.492] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:33.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:82:0) after 1m0s
[08:15:33.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:82:0)
[08:15:33.516] Timer tick, asking Buildbucket for the build status
[08:15:33.583] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:33.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:83:0) after 1m0s
[08:16:33.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:83:0)
[08:16:33.598] Timer tick, asking Buildbucket for the build status
[08:16:33.640] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:33.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:84:0) after 1m0s
[08:17:33.635] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:84:0)
[08:17:33.635] Timer tick, asking Buildbucket for the build status
[08:17:33.768] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:33.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:85:0) after 1m0s
[08:18:33.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:85:0)
[08:18:33.810] Timer tick, asking Buildbucket for the build status
[08:18:33.933] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:33.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:86:0) after 1m0s
[08:19:33.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:86:0)
[08:19:33.965] Timer tick, asking Buildbucket for the build status
[08:19:34.073] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:34.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:87:0) after 1m0s
[08:20:34.050] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:87:0)
[08:20:34.050] Timer tick, asking Buildbucket for the build status
[08:20:34.104] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:34.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:88:0) after 1m0s
[08:21:34.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:88:0)
[08:21:34.139] Timer tick, asking Buildbucket for the build status
[08:21:34.250] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:34.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:89:0) after 1m0s
[08:22:34.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:89:0)
[08:22:34.259] Timer tick, asking Buildbucket for the build status
[08:22:34.356] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:34.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:90:0) after 1m0s
[08:23:34.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:90:0)
[08:23:34.377] Timer tick, asking Buildbucket for the build status
[08:23:34.593] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:34.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:91:0) after 1m0s
[08:24:34.613] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:91:0)
[08:24:34.613] Timer tick, asking Buildbucket for the build status
[08:24:34.696] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:34.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:92:0) after 1m0s
[08:25:34.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:92:0)
[08:25:34.719] Timer tick, asking Buildbucket for the build status
[08:25:34.843] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:34.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:93:0) after 1m0s
[08:26:34.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:93:0)
[08:26:34.869] Timer tick, asking Buildbucket for the build status
[08:26:34.909] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:34.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:94:0) after 1m0s
[08:27:34.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:94:0)
[08:27:34.927] Timer tick, asking Buildbucket for the build status
[08:27:34.999] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:34.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:95:0) after 1m0s
[08:28:34.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:95:0)
[08:28:34.980] Timer tick, asking Buildbucket for the build status
[08:28:35.021] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:35.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:96:0) after 1m0s
[08:29:35.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:96:0)
[08:29:35.045] Timer tick, asking Buildbucket for the build status
[08:29:35.099] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:35.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:97:0) after 1m0s
[08:30:35.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:97:0)
[08:30:35.096] Timer tick, asking Buildbucket for the build status
[08:30:35.165] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:35.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:98:0) after 1m0s
[08:31:35.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:98:0)
[08:31:35.196] Timer tick, asking Buildbucket for the build status
[08:31:35.302] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:35.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:99:0) after 1m0s
[08:32:35.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:99:0)
[08:32:35.323] Timer tick, asking Buildbucket for the build status
[08:32:35.481] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:35.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:100:0) after 1m0s
[08:33:35.504] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:100:0)
[08:33:35.504] Timer tick, asking Buildbucket for the build status
[08:33:35.565] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:35.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:101:0) after 1m0s
[08:34:35.585] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:101:0)
[08:34:35.585] Timer tick, asking Buildbucket for the build status
[08:34:35.630] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:35.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:102:0) after 1m0s
[08:35:35.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:102:0)
[08:35:35.649] Timer tick, asking Buildbucket for the build status
[08:35:35.704] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:35.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:103:0) after 1m0s
[08:36:35.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:103:0)
[08:36:35.700] Timer tick, asking Buildbucket for the build status
[08:36:35.802] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:35.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:104:0) after 1m0s
[08:37:35.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:104:0)
[08:37:35.815] Timer tick, asking Buildbucket for the build status
[08:37:35.948] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:35.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:105:0) after 1m0s
[08:38:35.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:105:0)
[08:38:35.968] Timer tick, asking Buildbucket for the build status
[08:38:36.055] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:36.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:106:0) after 1m0s
[08:39:36.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:106:0)
[08:39:36.073] Timer tick, asking Buildbucket for the build status
[08:39:36.127] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:36.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:107:0) after 1m0s
[08:40:36.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:107:0)
[08:40:36.169] Timer tick, asking Buildbucket for the build status
[08:40:36.207] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:36.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:108:0) after 1m0s
[08:41:36.226] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:108:0)
[08:41:36.226] Timer tick, asking Buildbucket for the build status
[08:41:36.287] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:36.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:109:0) after 1m0s
[08:42:36.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:109:0)
[08:42:36.304] Timer tick, asking Buildbucket for the build status
[08:42:36.349] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:36.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:110:0) after 1m0s
[08:43:36.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:110:0)
[08:43:36.372] Timer tick, asking Buildbucket for the build status
[08:43:36.437] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:36.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:111:0) after 1m0s
[08:44:36.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:111:0)
[08:44:36.443] Timer tick, asking Buildbucket for the build status
[08:44:37.652] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:37.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:112:0) after 1m0s
[08:45:37.657] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:112:0)
[08:45:37.657] Timer tick, asking Buildbucket for the build status
[08:45:37.874] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:37.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:113:0) after 1m0s
[08:46:37.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:113:0)
[08:46:37.906] Timer tick, asking Buildbucket for the build status
[08:46:37.945] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:37.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:114:0) after 1m0s
[08:47:37.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:114:0)
[08:47:37.971] Timer tick, asking Buildbucket for the build status
[08:47:38.256] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:38.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:115:0) after 1m0s
[08:48:38.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:115:0)
[08:48:38.297] Timer tick, asking Buildbucket for the build status
[08:48:38.334] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:38.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:116:0) after 1m0s
[08:49:38.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:116:0)
[08:49:38.359] Timer tick, asking Buildbucket for the build status
[08:49:38.586] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:38.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:117:0) after 1m0s
[08:50:38.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:117:0)
[08:50:38.606] Timer tick, asking Buildbucket for the build status
[08:50:38.644] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:38.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:118:0) after 1m0s
[08:51:38.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:118:0)
[08:51:38.698] Timer tick, asking Buildbucket for the build status
[08:51:38.756] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:38.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:119:0) after 1m0s
[08:52:38.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:119:0)
[08:52:38.819] Timer tick, asking Buildbucket for the build status
[08:52:38.875] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:38.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:120:0) after 1m0s
[08:53:38.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:120:0)
[08:53:38.906] Timer tick, asking Buildbucket for the build status
[08:53:38.983] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:38.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:121:0) after 1m0s
[08:54:38.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:121:0)
[08:54:38.964] Timer tick, asking Buildbucket for the build status
[08:54:38.999] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:38.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:122:0) after 1m0s
[08:55:39.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:122:0)
[08:55:39.001] Timer tick, asking Buildbucket for the build status
[08:55:39.148] Build 8915225622123562048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:39.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080655686261756272:123:0) after 1m0s
[08:56:13.574] Received PubSub notification, asking Buildbucket for the build status
[08:56:13.716] Build 8915225622123562048: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:56:13.716] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556182573133599",
"created_by": "project:fuchsia",
"created_ts": "1556175321421512",
"failure_reason": "BUILD_FAILURE",
"id": "8915225622123562048",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"013503c78d7b648152a07022e75b98219aba2ba9\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-6drl\", \"branch\": \"refs/heads/master\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"path_config\": \"generic\", \"recipe\": \"buildbucket_trigger\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"013503c78d7b648152a07022e75b98219aba2ba9\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-6drl\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4274-a0a5f8c\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1556175340948491",
"status": "COMPLETED",
"status_changed_ts": "1556175341263573",
"tags": [
"builder:clang",
"buildset:commit/git/013503c78d7b648152a07022e75b98219aba2ba9",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/013503c78d7b648152a07022e75b98219aba2ba9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080655686261756272",
"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/8915225622123562048/+/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:446c18841e638b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1556182573246759",
"url": "https://ci.chromium.org/b/8915225622123562048",
"utcnow_ts": "1556182573703073"
}
}
[08:56:13.716] Invocation finished in 2h0m55.624924691s with status FAILED