[20:21:12.248] New invocation is queued and will start shortly
[20:21:13.304] Starting the invocation (attempt 1)
[20:21:13.372] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:21:13.372] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:21:13.372] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9082779310464882672",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"66d7eb97045e0fda283ff336297e1a46869152f6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9082779310464882672",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/66d7eb97045e0fda283ff336297e1a46869152f6",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/66d7eb97045e0fda283ff336297e1a46869152f6",
"gitiles_ref:refs/heads/master"
]
}
[20:21:14.133] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1554150073612067",
"id": "8917349248370288080",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"66d7eb97045e0fda283ff336297e1a46869152f6\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554150073992871",
"tags": [
"builder:clang",
"buildset:commit/git/66d7eb97045e0fda283ff336297e1a46869152f6",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/66d7eb97045e0fda283ff336297e1a46869152f6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082779310464882672",
"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/8917349248370288080/+/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": "1554150073993020",
"url": "https://ci.chromium.org/b/8917349248370288080",
"utcnow_ts": "1554150074121329"
}
}
[20:21:14.133] Task URL: https://ci.chromium.org/b/8917349248370288080
[20:21:14.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:2:0) after 1m0s
[20:21:29.402] Received PubSub notification, asking Buildbucket for the build status
[20:21:29.536] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:14.154] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:2:0)
[20:22:14.154] Timer tick, asking Buildbucket for the build status
[20:22:14.302] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:14.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:4:0) after 1m0s
[20:23:14.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:4:0)
[20:23:14.321] Timer tick, asking Buildbucket for the build status
[20:23:14.410] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:14.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:5:0) after 1m0s
[20:24:14.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:5:0)
[20:24:14.430] Timer tick, asking Buildbucket for the build status
[20:24:14.473] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:14.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:6:0) after 1m0s
[20:25:14.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:6:0)
[20:25:14.482] Timer tick, asking Buildbucket for the build status
[20:25:14.791] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:14.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:7:0) after 1m0s
[20:26:14.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:7:0)
[20:26:14.808] Timer tick, asking Buildbucket for the build status
[20:26:14.920] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:14.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:8:0) after 1m0s
[20:27:14.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:8:0)
[20:27:14.938] Timer tick, asking Buildbucket for the build status
[20:27:14.984] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:14.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:9:0) after 1m0s
[20:28:15.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:9:0)
[20:28:15.002] Timer tick, asking Buildbucket for the build status
[20:28:15.050] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:15.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:10:0) after 1m0s
[20:29:15.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:10:0)
[20:29:15.069] Timer tick, asking Buildbucket for the build status
[20:29:15.169] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:15.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:11:0) after 1m0s
[20:30:15.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:11:0)
[20:30:15.186] Timer tick, asking Buildbucket for the build status
[20:30:15.447] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:15.447] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:12:0) after 1m0s
[20:31:15.466] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:12:0)
[20:31:15.466] Timer tick, asking Buildbucket for the build status
[20:31:15.502] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:15.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:13:0) after 1m0s
[20:32:15.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:13:0)
[20:32:15.522] Timer tick, asking Buildbucket for the build status
[20:32:15.604] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:15.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:14:0) after 1m0s
[20:33:15.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:14:0)
[20:33:15.620] Timer tick, asking Buildbucket for the build status
[20:33:15.777] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:15.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:15:0) after 1m0s
[20:34:15.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:15:0)
[20:34:15.794] Timer tick, asking Buildbucket for the build status
[20:34:15.867] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:15.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:16:0) after 1m0s
[20:35:15.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:16:0)
[20:35:15.845] Timer tick, asking Buildbucket for the build status
[20:35:15.909] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:15.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:17:0) after 1m0s
[20:36:15.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:17:0)
[20:36:15.926] Timer tick, asking Buildbucket for the build status
[20:36:16.266] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:16.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:18:0) after 1m0s
[20:37:16.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:18:0)
[20:37:16.287] Timer tick, asking Buildbucket for the build status
[20:37:16.446] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:16.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:19:0) after 1m0s
[20:38:16.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:19:0)
[20:38:16.463] Timer tick, asking Buildbucket for the build status
[20:38:16.564] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:16.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:20:0) after 1m0s
[20:39:16.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:20:0)
[20:39:16.581] Timer tick, asking Buildbucket for the build status
[20:39:16.783] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:16.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:21:0) after 1m0s
[20:40:16.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:21:0)
[20:40:16.808] Timer tick, asking Buildbucket for the build status
[20:40:16.851] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:16.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:22:0) after 1m0s
[20:41:16.835] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:22:0)
[20:41:16.835] Timer tick, asking Buildbucket for the build status
[20:41:16.977] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:16.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:23:0) after 1m0s
[20:42:16.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:23:0)
[20:42:16.999] Timer tick, asking Buildbucket for the build status
[20:42:17.085] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:17.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:24:0) after 1m0s
[20:43:17.076] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:24:0)
[20:43:17.076] Timer tick, asking Buildbucket for the build status
[20:43:17.204] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:17.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:25:0) after 1m0s
[20:44:17.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:25:0)
[20:44:17.223] Timer tick, asking Buildbucket for the build status
[20:44:17.467] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:17.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:26:0) after 1m0s
[20:45:17.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:26:0)
[20:45:17.490] Timer tick, asking Buildbucket for the build status
[20:45:17.817] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:17.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:27:0) after 1m0s
[20:46:17.843] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:27:0)
[20:46:17.843] Timer tick, asking Buildbucket for the build status
[20:46:18.076] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:18.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:28:0) after 1m0s
[20:47:18.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:28:0)
[20:47:18.097] Timer tick, asking Buildbucket for the build status
[20:47:18.170] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:18.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:29:0) after 1m0s
[20:48:18.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:29:0)
[20:48:18.191] Timer tick, asking Buildbucket for the build status
[20:48:18.232] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:18.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:30:0) after 1m0s
[20:49:18.260] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:30:0)
[20:49:18.260] Timer tick, asking Buildbucket for the build status
[20:49:18.333] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:18.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:31:0) after 1m0s
[20:50:18.353] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:31:0)
[20:50:18.353] Timer tick, asking Buildbucket for the build status
[20:50:18.509] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:18.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:32:0) after 1m0s
[20:51:18.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:32:0)
[20:51:18.534] Timer tick, asking Buildbucket for the build status
[20:51:18.699] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:18.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:33:0) after 1m0s
[20:52:18.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:33:0)
[20:52:18.794] Timer tick, asking Buildbucket for the build status
[20:52:18.839] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:18.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:34:0) after 1m0s
[20:53:18.861] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:34:0)
[20:53:18.861] Timer tick, asking Buildbucket for the build status
[20:53:18.921] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:18.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:35:0) after 1m0s
[20:54:18.941] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:35:0)
[20:54:18.941] Timer tick, asking Buildbucket for the build status
[20:54:19.146] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:19.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:36:0) after 1m0s
[20:55:19.150] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:36:0)
[20:55:19.150] Timer tick, asking Buildbucket for the build status
[20:55:19.288] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:19.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:37:0) after 1m0s
[20:56:19.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:37:0)
[20:56:19.332] Timer tick, asking Buildbucket for the build status
[20:56:19.464] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:19.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:38:0) after 1m0s
[20:57:19.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:38:0)
[20:57:19.485] Timer tick, asking Buildbucket for the build status
[20:57:19.606] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:19.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:39:0) after 1m0s
[20:58:19.632] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:39:0)
[20:58:19.632] Timer tick, asking Buildbucket for the build status
[20:58:19.682] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:19.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:40:0) after 1m0s
[20:59:19.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:40:0)
[20:59:19.775] Timer tick, asking Buildbucket for the build status
[20:59:19.879] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:19.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:41:0) after 1m0s
[21:00:19.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:41:0)
[21:00:19.862] Timer tick, asking Buildbucket for the build status
[21:00:19.926] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:19.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:42:0) after 1m0s
[21:01:19.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:42:0)
[21:01:19.943] Timer tick, asking Buildbucket for the build status
[21:01:20.001] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:20.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:43:0) after 1m0s
[21:02:20.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:43:0)
[21:02:20.075] Timer tick, asking Buildbucket for the build status
[21:02:20.125] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:20.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:44:0) after 1m0s
[21:03:20.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:44:0)
[21:03:20.153] Timer tick, asking Buildbucket for the build status
[21:03:20.224] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:20.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:45:0) after 1m0s
[21:04:20.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:45:0)
[21:04:20.332] Timer tick, asking Buildbucket for the build status
[21:04:20.401] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:20.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:46:0) after 1m0s
[21:05:20.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:46:0)
[21:05:20.402] Timer tick, asking Buildbucket for the build status
[21:05:20.557] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:20.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:47:0) after 1m0s
[21:06:20.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:47:0)
[21:06:20.686] Timer tick, asking Buildbucket for the build status
[21:06:20.838] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:20.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:48:0) after 1m0s
[21:07:20.856] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:48:0)
[21:07:20.856] Timer tick, asking Buildbucket for the build status
[21:07:21.029] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:21.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:49:0) after 1m0s
[21:08:21.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:49:0)
[21:08:21.049] Timer tick, asking Buildbucket for the build status
[21:08:22.524] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:22.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:50:0) after 1m0s
[21:09:22.504] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:50:0)
[21:09:22.504] Timer tick, asking Buildbucket for the build status
[21:09:22.649] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:22.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:51:0) after 1m0s
[21:10:22.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:51:0)
[21:10:22.669] Timer tick, asking Buildbucket for the build status
[21:10:22.918] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:22.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:52:0) after 1m0s
[21:11:22.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:52:0)
[21:11:22.910] Timer tick, asking Buildbucket for the build status
[21:11:23.039] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:23.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:53:0) after 1m0s
[21:12:23.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:53:0)
[21:12:23.072] Timer tick, asking Buildbucket for the build status
[21:12:23.166] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:23.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:54:0) after 1m0s
[21:13:23.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:54:0)
[21:13:23.157] Timer tick, asking Buildbucket for the build status
[21:13:24.171] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:24.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:55:0) after 1m0s
[21:14:24.178] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:55:0)
[21:14:24.178] Timer tick, asking Buildbucket for the build status
[21:14:24.452] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:24.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:56:0) after 1m0s
[21:15:24.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:56:0)
[21:15:24.431] Timer tick, asking Buildbucket for the build status
[21:15:24.477] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:24.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:57:0) after 1m0s
[21:16:24.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:57:0)
[21:16:24.506] Timer tick, asking Buildbucket for the build status
[21:16:24.546] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:24.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:58:0) after 1m0s
[21:17:24.587] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:58:0)
[21:17:24.587] Timer tick, asking Buildbucket for the build status
[21:17:24.695] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:24.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:59:0) after 1m0s
[21:18:24.740] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:59:0)
[21:18:24.740] Timer tick, asking Buildbucket for the build status
[21:18:25.027] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:25.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:60:0) after 1m0s
[21:19:25.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:60:0)
[21:19:25.047] Timer tick, asking Buildbucket for the build status
[21:19:25.107] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:25.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:61:0) after 1m0s
[21:20:25.126] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:61:0)
[21:20:25.127] Timer tick, asking Buildbucket for the build status
[21:20:25.226] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:25.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:62:0) after 1m0s
[21:21:25.262] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:62:0)
[21:21:25.262] Timer tick, asking Buildbucket for the build status
[21:21:25.468] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:25.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:63:0) after 1m0s
[21:22:25.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:63:0)
[21:22:25.465] Timer tick, asking Buildbucket for the build status
[21:22:25.610] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:25.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:64:0) after 1m0s
[21:23:25.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:64:0)
[21:23:25.628] Timer tick, asking Buildbucket for the build status
[21:23:25.667] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:25.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:65:0) after 1m0s
[21:24:25.691] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:65:0)
[21:24:25.691] Timer tick, asking Buildbucket for the build status
[21:24:26.055] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:26.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:66:0) after 1m0s
[21:25:26.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:66:0)
[21:25:26.037] Timer tick, asking Buildbucket for the build status
[21:25:26.121] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:26.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:67:0) after 1m0s
[21:26:26.141] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:67:0)
[21:26:26.141] Timer tick, asking Buildbucket for the build status
[21:26:26.205] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:26.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:68:0) after 1m0s
[21:27:26.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:68:0)
[21:27:26.200] Timer tick, asking Buildbucket for the build status
[21:27:26.251] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:26.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:69:0) after 1m0s
[21:28:26.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:69:0)
[21:28:26.283] Timer tick, asking Buildbucket for the build status
[21:28:26.324] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:26.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:70:0) after 1m0s
[21:29:26.345] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:70:0)
[21:29:26.345] Timer tick, asking Buildbucket for the build status
[21:29:26.545] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:26.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:71:0) after 1m0s
[21:30:26.549] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:71:0)
[21:30:26.549] Timer tick, asking Buildbucket for the build status
[21:30:26.614] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:26.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:72:0) after 1m0s
[21:31:26.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:72:0)
[21:31:26.620] Timer tick, asking Buildbucket for the build status
[21:31:26.769] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:26.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:73:0) after 1m0s
[21:32:26.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:73:0)
[21:32:26.773] Timer tick, asking Buildbucket for the build status
[21:32:26.820] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:26.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:74:0) after 1m0s
[21:33:26.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:74:0)
[21:33:26.804] Timer tick, asking Buildbucket for the build status
[21:33:28.285] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:28.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:75:0) after 1m0s
[21:34:28.310] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:75:0)
[21:34:28.310] Timer tick, asking Buildbucket for the build status
[21:34:28.357] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:28.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:76:0) after 1m0s
[21:35:28.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:76:0)
[21:35:28.380] Timer tick, asking Buildbucket for the build status
[21:35:28.469] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:28.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:77:0) after 1m0s
[21:36:28.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:77:0)
[21:36:28.449] Timer tick, asking Buildbucket for the build status
[21:36:29.150] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:29.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:78:0) after 1m0s
[21:37:29.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:78:0)
[21:37:29.173] Timer tick, asking Buildbucket for the build status
[21:37:29.223] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:29.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:79:0) after 1m0s
[21:38:29.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:79:0)
[21:38:29.249] Timer tick, asking Buildbucket for the build status
[21:38:29.295] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:29.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:80:0) after 1m0s
[21:39:29.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:80:0)
[21:39:29.276] Timer tick, asking Buildbucket for the build status
[21:39:29.353] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:29.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:81:0) after 1m0s
[21:40:29.381] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:81:0)
[21:40:29.381] Timer tick, asking Buildbucket for the build status
[21:40:29.427] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:29.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:82:0) after 1m0s
[21:41:29.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:82:0)
[21:41:29.507] Timer tick, asking Buildbucket for the build status
[21:41:29.690] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:29.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:83:0) after 1m0s
[21:42:29.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:83:0)
[21:42:29.692] Timer tick, asking Buildbucket for the build status
[21:42:30.124] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:30.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:84:0) after 1m0s
[21:43:30.137] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:84:0)
[21:43:30.137] Timer tick, asking Buildbucket for the build status
[21:43:30.190] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:30.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:85:0) after 1m0s
[21:44:30.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:85:0)
[21:44:30.215] Timer tick, asking Buildbucket for the build status
[21:44:30.378] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:30.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:86:0) after 1m0s
[21:45:30.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:86:0)
[21:45:30.394] Timer tick, asking Buildbucket for the build status
[21:45:30.548] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:30.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:87:0) after 1m0s
[21:46:30.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:87:0)
[21:46:30.569] Timer tick, asking Buildbucket for the build status
[21:46:30.745] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:30.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:88:0) after 1m0s
[21:47:30.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:88:0)
[21:47:30.771] Timer tick, asking Buildbucket for the build status
[21:47:30.811] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:30.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:89:0) after 1m0s
[21:48:30.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:89:0)
[21:48:30.831] Timer tick, asking Buildbucket for the build status
[21:48:31.224] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:31.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:90:0) after 1m0s
[21:49:31.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:90:0)
[21:49:31.257] Timer tick, asking Buildbucket for the build status
[21:49:31.537] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:31.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:91:0) after 1m0s
[21:50:31.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:91:0)
[21:50:31.561] Timer tick, asking Buildbucket for the build status
[21:50:31.630] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:31.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:92:0) after 1m0s
[21:51:31.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:92:0)
[21:51:31.651] Timer tick, asking Buildbucket for the build status
[21:51:31.733] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:31.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:93:0) after 1m0s
[21:52:31.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:93:0)
[21:52:31.769] Timer tick, asking Buildbucket for the build status
[21:52:31.811] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:31.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:94:0) after 1m0s
[21:53:31.858] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:94:0)
[21:53:31.858] Timer tick, asking Buildbucket for the build status
[21:53:31.974] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:31.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:95:0) after 1m0s
[21:54:31.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:95:0)
[21:54:31.999] Timer tick, asking Buildbucket for the build status
[21:54:32.038] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:32.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:96:0) after 1m0s
[21:55:32.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:96:0)
[21:55:32.028] Timer tick, asking Buildbucket for the build status
[21:55:32.297] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:32.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:97:0) after 1m0s
[21:56:32.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:97:0)
[21:56:32.280] Timer tick, asking Buildbucket for the build status
[21:56:32.355] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:32.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:98:0) after 1m0s
[21:57:32.341] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:98:0)
[21:57:32.341] Timer tick, asking Buildbucket for the build status
[21:57:32.400] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:32.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:99:0) after 1m0s
[21:58:32.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:99:0)
[21:58:32.377] Timer tick, asking Buildbucket for the build status
[21:58:32.471] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:32.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:100:0) after 1m0s
[21:59:32.500] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:100:0)
[21:59:32.500] Timer tick, asking Buildbucket for the build status
[21:59:32.683] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:32.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:101:0) after 1m0s
[22:00:32.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:101:0)
[22:00:32.707] Timer tick, asking Buildbucket for the build status
[22:00:32.832] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:32.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:102:0) after 1m0s
[22:01:32.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:102:0)
[22:01:32.854] Timer tick, asking Buildbucket for the build status
[22:01:32.952] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:32.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:103:0) after 1m0s
[22:02:32.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:103:0)
[22:02:32.957] Timer tick, asking Buildbucket for the build status
[22:02:33.012] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:33.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:104:0) after 1m0s
[22:03:33.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:104:0)
[22:03:33.026] Timer tick, asking Buildbucket for the build status
[22:03:33.064] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:33.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:105:0) after 1m0s
[22:04:33.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:105:0)
[22:04:33.053] Timer tick, asking Buildbucket for the build status
[22:04:33.101] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:33.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:106:0) after 1m0s
[22:05:33.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:106:0)
[22:05:33.128] Timer tick, asking Buildbucket for the build status
[22:05:33.180] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:33.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:107:0) after 1m0s
[22:06:33.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:107:0)
[22:06:33.194] Timer tick, asking Buildbucket for the build status
[22:06:33.252] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:33.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:108:0) after 1m0s
[22:07:33.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:108:0)
[22:07:33.236] Timer tick, asking Buildbucket for the build status
[22:07:33.279] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:33.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:109:0) after 1m0s
[22:08:33.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:109:0)
[22:08:33.281] Timer tick, asking Buildbucket for the build status
[22:08:33.335] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:33.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:110:0) after 1m0s
[22:09:33.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:110:0)
[22:09:33.433] Timer tick, asking Buildbucket for the build status
[22:09:33.487] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:33.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:111:0) after 1m0s
[22:10:33.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:111:0)
[22:10:33.534] Timer tick, asking Buildbucket for the build status
[22:10:33.634] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:33.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:112:0) after 1m0s
[22:11:33.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:112:0)
[22:11:33.617] Timer tick, asking Buildbucket for the build status
[22:11:33.984] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:33.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:113:0) after 1m0s
[22:12:33.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:113:0)
[22:12:33.990] Timer tick, asking Buildbucket for the build status
[22:12:34.304] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:34.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:114:0) after 1m0s
[22:13:34.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:114:0)
[22:13:34.321] Timer tick, asking Buildbucket for the build status
[22:13:34.380] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:34.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:115:0) after 1m0s
[22:14:34.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:115:0)
[22:14:34.383] Timer tick, asking Buildbucket for the build status
[22:14:34.570] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:34.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:116:0) after 1m0s
[22:15:34.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:116:0)
[22:15:34.602] Timer tick, asking Buildbucket for the build status
[22:15:34.645] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:34.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:117:0) after 1m0s
[22:16:34.663] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:117:0)
[22:16:34.663] Timer tick, asking Buildbucket for the build status
[22:16:34.727] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:34.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:118:0) after 1m0s
[22:17:34.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:118:0)
[22:17:34.752] Timer tick, asking Buildbucket for the build status
[22:17:34.815] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:34.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:119:0) after 1m0s
[22:18:34.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:119:0)
[22:18:34.837] Timer tick, asking Buildbucket for the build status
[22:18:34.902] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:34.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:120:0) after 1m0s
[22:19:34.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:120:0)
[22:19:34.880] Timer tick, asking Buildbucket for the build status
[22:19:34.951] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:34.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:121:0) after 1m0s
[22:20:34.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:121:0)
[22:20:34.972] Timer tick, asking Buildbucket for the build status
[22:20:35.013] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:35.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:122:0) after 1m0s
[22:21:35.042] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:122:0)
[22:21:35.042] Timer tick, asking Buildbucket for the build status
[22:21:35.085] Build 8917349248370288080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:35.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082779310464882672:123:0) after 1m0s
[22:22:06.733] Received PubSub notification, asking Buildbucket for the build status
[22:22:07.287] Build 8917349248370288080: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:22:07.287] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1554157326413628",
"created_by": "project:fuchsia",
"created_ts": "1554150073612067",
"failure_reason": "BUILD_FAILURE",
"id": "8917349248370288080",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"66d7eb97045e0fda283ff336297e1a46869152f6\"}}",
"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-rz7f\", \"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\": \"66d7eb97045e0fda283ff336297e1a46869152f6\", \"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-rz7f\"], \"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\": [\"4231-d78bb73\"], \"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": "1554150088735523",
"status": "COMPLETED",
"status_changed_ts": "1554150089121526",
"tags": [
"builder:clang",
"buildset:commit/git/66d7eb97045e0fda283ff336297e1a46869152f6",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/66d7eb97045e0fda283ff336297e1a46869152f6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082779310464882672",
"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/8917349248370288080/+/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:43f361afee7ce510",
"user_agent:luci-scheduler"
],
"updated_ts": "1554157326481047",
"url": "https://ci.chromium.org/b/8917349248370288080",
"utcnow_ts": "1554157327236560"
}
}
[22:22:07.287] Invocation finished in 2h0m55.05598652s with status FAILED