[00:08:16.283] New invocation is queued and will start shortly
[00:08:17.356] Starting the invocation (attempt 1)
[00:08:17.421] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:08:17.421] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:08:17.421] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9082402636761913520",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"ad8d06b6877d0436af606e24009276b104f1a14a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9082402636761913520",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/ad8d06b6877d0436af606e24009276b104f1a14a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ad8d06b6877d0436af606e24009276b104f1a14a",
"gitiles_ref:refs/heads/master"
]
}
[00:08:17.944] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1554509297501882",
"id": "8916972574821815984",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"ad8d06b6877d0436af606e24009276b104f1a14a\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554509297821007",
"tags": [
"builder:clang",
"buildset:commit/git/ad8d06b6877d0436af606e24009276b104f1a14a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ad8d06b6877d0436af606e24009276b104f1a14a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082402636761913520",
"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/8916972574821815984/+/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": "1554509297821108",
"url": "https://ci.chromium.org/b/8916972574821815984",
"utcnow_ts": "1554509297933051"
}
}
[00:08:17.944] Task URL: https://ci.chromium.org/b/8916972574821815984
[00:08:17.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:2:0) after 1m0s
[00:08:32.694] Received PubSub notification, asking Buildbucket for the build status
[00:08:32.762] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:17.944] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:2:0)
[00:09:17.944] Timer tick, asking Buildbucket for the build status
[00:09:18.084] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:18.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:4:0) after 1m0s
[00:10:18.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:4:0)
[00:10:18.202] Timer tick, asking Buildbucket for the build status
[00:10:18.681] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:18.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:5:0) after 1m0s
[00:11:18.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:5:0)
[00:11:18.700] Timer tick, asking Buildbucket for the build status
[00:11:18.770] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:18.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:6:0) after 1m0s
[00:12:18.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:6:0)
[00:12:18.771] Timer tick, asking Buildbucket for the build status
[00:12:18.941] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:18.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:7:0) after 1m0s
[00:13:18.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:7:0)
[00:13:18.959] Timer tick, asking Buildbucket for the build status
[00:13:19.144] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:19.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:8:0) after 1m0s
[00:14:19.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:8:0)
[00:14:19.164] Timer tick, asking Buildbucket for the build status
[00:14:19.345] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:19.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:9:0) after 1m0s
[00:15:19.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:9:0)
[00:15:19.343] Timer tick, asking Buildbucket for the build status
[00:15:19.706] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:19.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:10:0) after 1m0s
[00:16:19.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:10:0)
[00:16:19.726] Timer tick, asking Buildbucket for the build status
[00:16:19.794] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:19.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:11:0) after 1m0s
[00:17:19.814] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:11:0)
[00:17:19.814] Timer tick, asking Buildbucket for the build status
[00:17:19.861] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:19.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:12:0) after 1m0s
[00:18:19.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:12:0)
[00:18:19.869] Timer tick, asking Buildbucket for the build status
[00:18:19.937] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:19.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:13:0) after 1m0s
[00:19:19.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:13:0)
[00:19:19.920] Timer tick, asking Buildbucket for the build status
[00:19:20.082] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:20.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:14:0) after 1m0s
[00:20:20.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:14:0)
[00:20:20.096] Timer tick, asking Buildbucket for the build status
[00:20:20.196] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:20.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:15:0) after 1m0s
[00:21:20.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:15:0)
[00:21:20.214] Timer tick, asking Buildbucket for the build status
[00:21:20.406] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:20.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:16:0) after 1m0s
[00:22:20.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:16:0)
[00:22:20.423] Timer tick, asking Buildbucket for the build status
[00:22:20.566] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:20.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:17:0) after 1m0s
[00:23:20.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:17:0)
[00:23:20.584] Timer tick, asking Buildbucket for the build status
[00:23:20.715] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:20.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:18:0) after 1m0s
[00:24:20.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:18:0)
[00:24:20.715] Timer tick, asking Buildbucket for the build status
[00:24:20.784] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:20.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:19:0) after 1m0s
[00:25:20.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:19:0)
[00:25:20.770] Timer tick, asking Buildbucket for the build status
[00:25:20.808] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:20.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:20:0) after 1m0s
[00:26:20.844] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:20:0)
[00:26:20.844] Timer tick, asking Buildbucket for the build status
[00:26:20.908] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:20.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:21:0) after 1m0s
[00:27:20.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:21:0)
[00:27:20.930] Timer tick, asking Buildbucket for the build status
[00:27:20.976] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:20.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:22:0) after 1m0s
[00:28:20.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:22:0)
[00:28:20.973] Timer tick, asking Buildbucket for the build status
[00:28:21.095] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:21.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:23:0) after 1m0s
[00:29:21.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:23:0)
[00:29:21.096] Timer tick, asking Buildbucket for the build status
[00:29:21.155] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:21.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:24:0) after 1m0s
[00:30:21.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:24:0)
[00:30:21.174] Timer tick, asking Buildbucket for the build status
[00:30:21.466] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:21.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:25:0) after 1m0s
[00:31:21.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:25:0)
[00:31:21.485] Timer tick, asking Buildbucket for the build status
[00:31:21.679] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:21.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:26:0) after 1m0s
[00:32:21.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:26:0)
[00:32:21.700] Timer tick, asking Buildbucket for the build status
[00:32:21.979] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:21.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:27:0) after 1m0s
[00:33:21.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:27:0)
[00:33:21.999] Timer tick, asking Buildbucket for the build status
[00:33:22.038] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:22.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:28:0) after 1m0s
[00:34:22.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:28:0)
[00:34:22.057] Timer tick, asking Buildbucket for the build status
[00:34:22.184] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:22.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:29:0) after 1m0s
[00:35:22.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:29:0)
[00:35:22.223] Timer tick, asking Buildbucket for the build status
[00:35:22.324] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:22.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:30:0) after 1m0s
[00:36:22.360] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:30:0)
[00:36:22.360] Timer tick, asking Buildbucket for the build status
[00:36:22.421] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:22.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:31:0) after 1m0s
[00:37:22.444] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:31:0)
[00:37:22.444] Timer tick, asking Buildbucket for the build status
[00:37:22.528] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:22.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:32:0) after 1m0s
[00:38:22.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:32:0)
[00:38:22.516] Timer tick, asking Buildbucket for the build status
[00:38:22.600] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:22.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:33:0) after 1m0s
[00:39:22.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:33:0)
[00:39:22.649] Timer tick, asking Buildbucket for the build status
[00:39:22.769] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:22.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:34:0) after 1m0s
[00:40:22.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:34:0)
[00:40:22.802] Timer tick, asking Buildbucket for the build status
[00:40:22.909] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:22.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:35:0) after 1m0s
[00:41:22.885] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:35:0)
[00:41:22.885] Timer tick, asking Buildbucket for the build status
[00:41:22.933] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:22.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:36:0) after 1m0s
[00:42:22.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:36:0)
[00:42:22.953] Timer tick, asking Buildbucket for the build status
[00:42:23.091] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:23.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:37:0) after 1m0s
[00:43:23.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:37:0)
[00:43:23.092] Timer tick, asking Buildbucket for the build status
[00:43:23.136] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:23.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:38:0) after 1m0s
[00:44:23.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:38:0)
[00:44:23.197] Timer tick, asking Buildbucket for the build status
[00:44:23.546] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:23.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:39:0) after 1m0s
[00:45:23.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:39:0)
[00:45:23.567] Timer tick, asking Buildbucket for the build status
[00:45:23.613] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:23.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:40:0) after 1m0s
[00:46:23.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:40:0)
[00:46:23.604] Timer tick, asking Buildbucket for the build status
[00:46:23.838] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:23.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:41:0) after 1m0s
[00:47:23.840] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:41:0)
[00:47:23.840] Timer tick, asking Buildbucket for the build status
[00:47:24.013] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:24.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:42:0) after 1m0s
[00:48:24.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:42:0)
[00:48:24.035] Timer tick, asking Buildbucket for the build status
[00:48:24.152] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:24.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:43:0) after 1m0s
[00:49:24.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:43:0)
[00:49:24.171] Timer tick, asking Buildbucket for the build status
[00:49:24.211] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:24.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:44:0) after 1m0s
[00:50:24.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:44:0)
[00:50:24.259] Timer tick, asking Buildbucket for the build status
[00:50:24.320] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:24.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:45:0) after 1m0s
[00:51:24.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:45:0)
[00:51:24.338] Timer tick, asking Buildbucket for the build status
[00:51:24.433] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:24.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:46:0) after 1m0s
[00:52:24.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:46:0)
[00:52:24.412] Timer tick, asking Buildbucket for the build status
[00:52:24.505] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:24.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:47:0) after 1m0s
[00:53:24.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:47:0)
[00:53:24.497] Timer tick, asking Buildbucket for the build status
[00:53:24.863] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:24.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:48:0) after 1m0s
[00:54:24.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:48:0)
[00:54:24.882] Timer tick, asking Buildbucket for the build status
[00:54:24.951] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:24.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:49:0) after 1m0s
[00:55:24.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:49:0)
[00:55:24.968] Timer tick, asking Buildbucket for the build status
[00:55:25.088] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:25.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:50:0) after 1m0s
[00:56:25.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:50:0)
[00:56:25.072] Timer tick, asking Buildbucket for the build status
[00:56:25.372] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:25.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:51:0) after 1m0s
[00:57:25.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:51:0)
[00:57:25.392] Timer tick, asking Buildbucket for the build status
[00:57:25.435] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:25.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:52:0) after 1m0s
[00:58:25.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:52:0)
[00:58:25.485] Timer tick, asking Buildbucket for the build status
[00:58:25.557] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:25.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:53:0) after 1m0s
[00:59:25.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:53:0)
[00:59:25.578] Timer tick, asking Buildbucket for the build status
[00:59:25.778] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:25.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:54:0) after 1m0s
[01:00:25.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:54:0)
[01:00:25.796] Timer tick, asking Buildbucket for the build status
[01:00:25.871] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:25.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:55:0) after 1m0s
[01:01:25.893] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:55:0)
[01:01:25.893] Timer tick, asking Buildbucket for the build status
[01:01:25.936] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:25.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:56:0) after 1m0s
[01:02:25.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:56:0)
[01:02:25.957] Timer tick, asking Buildbucket for the build status
[01:02:26.036] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:26.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:57:0) after 1m0s
[01:03:26.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:57:0)
[01:03:26.057] Timer tick, asking Buildbucket for the build status
[01:03:26.204] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:26.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:58:0) after 1m0s
[01:04:26.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:58:0)
[01:04:26.236] Timer tick, asking Buildbucket for the build status
[01:04:26.339] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:26.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:59:0) after 1m0s
[01:05:26.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:59:0)
[01:05:26.314] Timer tick, asking Buildbucket for the build status
[01:05:26.354] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:26.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:60:0) after 1m0s
[01:06:26.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:60:0)
[01:06:26.379] Timer tick, asking Buildbucket for the build status
[01:06:26.438] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:26.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:61:0) after 1m0s
[01:07:26.444] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:61:0)
[01:07:26.444] Timer tick, asking Buildbucket for the build status
[01:07:26.493] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:26.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:62:0) after 1m0s
[01:08:26.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:62:0)
[01:08:26.506] Timer tick, asking Buildbucket for the build status
[01:08:26.597] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:26.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:63:0) after 1m0s
[01:09:26.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:63:0)
[01:09:26.617] Timer tick, asking Buildbucket for the build status
[01:09:26.668] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:26.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:64:0) after 1m0s
[01:10:26.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:64:0)
[01:10:26.689] Timer tick, asking Buildbucket for the build status
[01:10:26.746] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:26.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:65:0) after 1m0s
[01:11:26.835] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:65:0)
[01:11:26.835] Timer tick, asking Buildbucket for the build status
[01:11:26.892] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:26.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:66:0) after 1m0s
[01:12:26.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:66:0)
[01:12:26.912] Timer tick, asking Buildbucket for the build status
[01:12:27.262] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:27.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:67:0) after 1m0s
[01:13:27.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:67:0)
[01:13:27.261] Timer tick, asking Buildbucket for the build status
[01:13:27.344] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:27.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:68:0) after 1m0s
[01:14:27.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:68:0)
[01:14:27.369] Timer tick, asking Buildbucket for the build status
[01:14:27.573] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:27.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:69:0) after 1m0s
[01:15:27.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:69:0)
[01:15:27.598] Timer tick, asking Buildbucket for the build status
[01:15:27.662] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:27.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:70:0) after 1m0s
[01:16:27.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:70:0)
[01:16:27.651] Timer tick, asking Buildbucket for the build status
[01:16:28.058] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:28.058] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:71:0) after 1m0s
[01:17:28.056] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:71:0)
[01:17:28.056] Timer tick, asking Buildbucket for the build status
[01:17:28.154] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:28.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:72:0) after 1m0s
[01:18:28.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:72:0)
[01:18:28.173] Timer tick, asking Buildbucket for the build status
[01:18:28.459] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:28.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:73:0) after 1m0s
[01:19:28.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:73:0)
[01:19:28.483] Timer tick, asking Buildbucket for the build status
[01:19:28.539] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:28.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:74:0) after 1m0s
[01:20:28.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:74:0)
[01:20:28.526] Timer tick, asking Buildbucket for the build status
[01:20:28.722] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:28.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:75:0) after 1m0s
[01:21:28.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:75:0)
[01:21:28.742] Timer tick, asking Buildbucket for the build status
[01:21:28.822] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:28.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:76:0) after 1m0s
[01:22:28.840] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:76:0)
[01:22:28.840] Timer tick, asking Buildbucket for the build status
[01:22:29.377] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:29.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:77:0) after 1m0s
[01:23:29.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:77:0)
[01:23:29.397] Timer tick, asking Buildbucket for the build status
[01:23:29.780] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:29.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:78:0) after 1m0s
[01:24:29.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:78:0)
[01:24:29.803] Timer tick, asking Buildbucket for the build status
[01:24:29.948] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:29.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:79:0) after 1m0s
[01:25:29.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:79:0)
[01:25:29.965] Timer tick, asking Buildbucket for the build status
[01:25:30.211] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:30.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:80:0) after 1m0s
[01:26:30.235] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:80:0)
[01:26:30.235] Timer tick, asking Buildbucket for the build status
[01:26:30.371] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:30.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:81:0) after 1m0s
[01:27:30.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:81:0)
[01:27:30.393] Timer tick, asking Buildbucket for the build status
[01:27:30.577] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:30.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:82:0) after 1m0s
[01:28:30.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:82:0)
[01:28:30.600] Timer tick, asking Buildbucket for the build status
[01:28:30.696] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:30.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:83:0) after 1m0s
[01:29:30.723] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:83:0)
[01:29:30.723] Timer tick, asking Buildbucket for the build status
[01:29:30.790] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:30.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:84:0) after 1m0s
[01:30:30.797] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:84:0)
[01:30:30.797] Timer tick, asking Buildbucket for the build status
[01:30:30.879] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:30.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:85:0) after 1m0s
[01:31:30.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:85:0)
[01:31:30.884] Timer tick, asking Buildbucket for the build status
[01:31:30.975] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:30.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:86:0) after 1m0s
[01:32:30.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:86:0)
[01:32:30.968] Timer tick, asking Buildbucket for the build status
[01:32:31.399] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:31.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:87:0) after 1m0s
[01:33:31.419] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:87:0)
[01:33:31.419] Timer tick, asking Buildbucket for the build status
[01:33:31.581] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:31.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:88:0) after 1m0s
[01:34:31.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:88:0)
[01:34:31.576] Timer tick, asking Buildbucket for the build status
[01:34:31.644] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:31.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:89:0) after 1m0s
[01:35:31.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:89:0)
[01:35:31.683] Timer tick, asking Buildbucket for the build status
[01:35:31.919] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:31.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:90:0) after 1m0s
[01:36:31.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:90:0)
[01:36:31.920] Timer tick, asking Buildbucket for the build status
[01:36:32.003] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:32.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:91:0) after 1m0s
[01:37:31.977] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:91:0)
[01:37:31.977] Timer tick, asking Buildbucket for the build status
[01:37:32.099] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:32.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:92:0) after 1m0s
[01:38:32.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:92:0)
[01:38:32.071] Timer tick, asking Buildbucket for the build status
[01:38:32.136] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:32.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:93:0) after 1m0s
[01:39:32.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:93:0)
[01:39:32.136] Timer tick, asking Buildbucket for the build status
[01:39:32.486] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:32.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:94:0) after 1m0s
[01:40:32.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:94:0)
[01:40:32.484] Timer tick, asking Buildbucket for the build status
[01:40:32.575] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:32.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:95:0) after 1m0s
[01:41:32.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:95:0)
[01:41:32.594] Timer tick, asking Buildbucket for the build status
[01:41:32.644] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:32.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:96:0) after 1m0s
[01:42:32.663] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:96:0)
[01:42:32.663] Timer tick, asking Buildbucket for the build status
[01:42:32.829] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:32.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:97:0) after 1m0s
[01:43:32.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:97:0)
[01:43:32.849] Timer tick, asking Buildbucket for the build status
[01:43:32.979] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:32.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:98:0) after 1m0s
[01:44:33.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:98:0)
[01:44:33.000] Timer tick, asking Buildbucket for the build status
[01:44:33.067] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:33.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:99:0) after 1m0s
[01:45:33.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:99:0)
[01:45:33.049] Timer tick, asking Buildbucket for the build status
[01:45:33.412] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:33.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:100:0) after 1m0s
[01:46:33.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:100:0)
[01:46:33.386] Timer tick, asking Buildbucket for the build status
[01:46:33.626] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:33.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:101:0) after 1m0s
[01:47:33.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:101:0)
[01:47:33.649] Timer tick, asking Buildbucket for the build status
[01:47:33.725] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:33.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:102:0) after 1m0s
[01:48:33.747] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:102:0)
[01:48:33.747] Timer tick, asking Buildbucket for the build status
[01:48:33.813] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:33.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:103:0) after 1m0s
[01:49:33.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:103:0)
[01:49:33.791] Timer tick, asking Buildbucket for the build status
[01:49:33.857] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:33.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:104:0) after 1m0s
[01:50:33.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:104:0)
[01:50:33.883] Timer tick, asking Buildbucket for the build status
[01:50:34.048] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:34.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:105:0) after 1m0s
[01:51:34.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:105:0)
[01:51:34.069] Timer tick, asking Buildbucket for the build status
[01:51:34.354] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:34.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:106:0) after 1m0s
[01:52:34.376] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:106:0)
[01:52:34.376] Timer tick, asking Buildbucket for the build status
[01:52:34.507] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:34.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:107:0) after 1m0s
[01:53:34.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:107:0)
[01:53:34.484] Timer tick, asking Buildbucket for the build status
[01:53:34.666] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:34.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:108:0) after 1m0s
[01:54:34.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:108:0)
[01:54:34.687] Timer tick, asking Buildbucket for the build status
[01:54:34.727] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:34.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:109:0) after 1m0s
[01:55:34.748] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:109:0)
[01:55:34.748] Timer tick, asking Buildbucket for the build status
[01:55:34.867] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:34.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:110:0) after 1m0s
[01:56:34.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:110:0)
[01:56:34.889] Timer tick, asking Buildbucket for the build status
[01:56:34.963] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:34.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:111:0) after 1m0s
[01:57:34.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:111:0)
[01:57:34.985] Timer tick, asking Buildbucket for the build status
[01:57:35.087] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:35.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:112:0) after 1m0s
[01:58:35.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:112:0)
[01:58:35.138] Timer tick, asking Buildbucket for the build status
[01:58:35.291] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:35.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:113:0) after 1m0s
[01:59:35.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:113:0)
[01:59:35.293] Timer tick, asking Buildbucket for the build status
[01:59:35.365] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:35.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:114:0) after 1m0s
[02:00:35.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:114:0)
[02:00:35.406] Timer tick, asking Buildbucket for the build status
[02:00:35.566] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:35.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:115:0) after 1m0s
[02:01:35.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:115:0)
[02:01:35.587] Timer tick, asking Buildbucket for the build status
[02:01:35.688] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:35.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:116:0) after 1m0s
[02:02:35.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:116:0)
[02:02:35.741] Timer tick, asking Buildbucket for the build status
[02:02:36.131] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:36.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:117:0) after 1m0s
[02:03:36.544] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:117:0)
[02:03:36.544] Timer tick, asking Buildbucket for the build status
[02:03:36.593] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:36.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:118:0) after 1m0s
[02:04:36.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:118:0)
[02:04:36.622] Timer tick, asking Buildbucket for the build status
[02:04:36.784] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:36.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:119:0) after 1m0s
[02:05:36.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:119:0)
[02:05:36.791] Timer tick, asking Buildbucket for the build status
[02:05:36.896] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:36.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:120:0) after 1m0s
[02:06:36.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:120:0)
[02:06:36.883] Timer tick, asking Buildbucket for the build status
[02:06:37.021] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:37.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:121:0) after 1m0s
[02:07:37.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:121:0)
[02:07:37.004] Timer tick, asking Buildbucket for the build status
[02:07:37.183] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:37.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:122:0) after 1m0s
[02:08:37.290] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:122:0)
[02:08:37.290] Timer tick, asking Buildbucket for the build status
[02:08:37.353] Build 8916972574821815984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:37.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082402636761913520:123:0) after 1m0s
[02:09:08.915] Received PubSub notification, asking Buildbucket for the build status
[02:09:08.966] Build 8916972574821815984: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:09:08.966] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1554516548496605",
"created_by": "project:fuchsia",
"created_ts": "1554509297501882",
"failure_reason": "BUILD_FAILURE",
"id": "8916972574821815984",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"ad8d06b6877d0436af606e24009276b104f1a14a\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-c-5bbx\", \"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\": \"ad8d06b6877d0436af606e24009276b104f1a14a\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-c-5bbx\"], \"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\": [\"4243-f38882f\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1554509312130039",
"status": "COMPLETED",
"status_changed_ts": "1554509312451280",
"tags": [
"builder:clang",
"buildset:commit/git/ad8d06b6877d0436af606e24009276b104f1a14a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ad8d06b6877d0436af606e24009276b104f1a14a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082402636761913520",
"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/8916972574821815984/+/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:4408cb0261bfb710",
"user_agent:luci-scheduler"
],
"updated_ts": "1554516548592665",
"url": "https://ci.chromium.org/b/8916972574821815984",
"utcnow_ts": "1554516548953739"
}
}
[02:09:08.966] Invocation finished in 2h0m52.699198755s with status FAILED