[19:04:16.715] New invocation is queued and will start shortly
[19:04:17.857] Starting the invocation (attempt 1)
[19:04:17.906] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:04:17.906] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:04:17.906] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083418328967300672",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"167af1bafbf0a2a9149f03315f7a30a8425db408\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083418328967300672",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/167af1bafbf0a2a9149f03315f7a30a8425db408",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/167af1bafbf0a2a9149f03315f7a30a8425db408",
"gitiles_ref:refs/heads/master"
]
}
[19:04:18.470] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553540658016004",
"id": "8917988266938404176",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"167af1bafbf0a2a9149f03315f7a30a8425db408\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553540658387878",
"tags": [
"builder:clang",
"buildset:commit/git/167af1bafbf0a2a9149f03315f7a30a8425db408",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/167af1bafbf0a2a9149f03315f7a30a8425db408",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083418328967300672",
"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/8917988266938404176/+/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": "1553540658388079",
"url": "https://ci.chromium.org/b/8917988266938404176",
"utcnow_ts": "1553540658460739"
}
}
[19:04:18.470] Task URL: https://ci.chromium.org/b/8917988266938404176
[19:04:18.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:2:0) after 1m0s
[19:04:57.379] Received PubSub notification, asking Buildbucket for the build status
[19:04:57.511] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:18.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:2:0)
[19:05:18.517] Timer tick, asking Buildbucket for the build status
[19:05:18.612] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:18.612] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:4:0) after 1m0s
[19:06:18.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:4:0)
[19:06:18.590] Timer tick, asking Buildbucket for the build status
[19:06:18.663] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:18.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:5:0) after 1m0s
[19:07:18.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:5:0)
[19:07:18.679] Timer tick, asking Buildbucket for the build status
[19:07:18.727] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:18.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:6:0) after 1m0s
[19:08:18.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:6:0)
[19:08:18.732] Timer tick, asking Buildbucket for the build status
[19:08:19.009] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:19.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:7:0) after 1m0s
[19:09:19.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:7:0)
[19:09:19.031] Timer tick, asking Buildbucket for the build status
[19:09:19.312] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:19.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:8:0) after 1m0s
[19:10:19.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:8:0)
[19:10:19.330] Timer tick, asking Buildbucket for the build status
[19:10:19.381] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:19.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:9:0) after 1m0s
[19:11:19.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:9:0)
[19:11:19.388] Timer tick, asking Buildbucket for the build status
[19:11:19.437] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:19.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:10:0) after 1m0s
[19:12:19.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:10:0)
[19:12:19.454] Timer tick, asking Buildbucket for the build status
[19:12:19.517] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:19.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:11:0) after 1m0s
[19:13:19.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:11:0)
[19:13:19.590] Timer tick, asking Buildbucket for the build status
[19:13:19.723] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:19.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:12:0) after 1m0s
[19:14:19.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:12:0)
[19:14:19.743] Timer tick, asking Buildbucket for the build status
[19:14:19.814] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:19.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:13:0) after 1m0s
[19:15:19.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:13:0)
[19:15:19.812] Timer tick, asking Buildbucket for the build status
[19:15:19.885] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:19.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:14:0) after 1m0s
[19:16:19.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:14:0)
[19:16:19.904] Timer tick, asking Buildbucket for the build status
[19:16:20.007] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:20.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:15:0) after 1m0s
[19:17:20.041] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:15:0)
[19:17:20.041] Timer tick, asking Buildbucket for the build status
[19:17:20.256] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:20.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:16:0) after 1m0s
[19:18:20.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:16:0)
[19:18:20.236] Timer tick, asking Buildbucket for the build status
[19:18:20.453] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:20.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:17:0) after 1m0s
[19:19:20.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:17:0)
[19:19:20.449] Timer tick, asking Buildbucket for the build status
[19:19:20.514] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:20.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:18:0) after 1m0s
[19:20:20.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:18:0)
[19:20:20.497] Timer tick, asking Buildbucket for the build status
[19:20:20.788] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:20.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:19:0) after 1m0s
[19:21:20.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:19:0)
[19:21:20.802] Timer tick, asking Buildbucket for the build status
[19:21:20.859] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:20.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:20:0) after 1m0s
[19:22:20.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:20:0)
[19:22:20.881] Timer tick, asking Buildbucket for the build status
[19:22:20.938] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:20.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:21:0) after 1m0s
[19:23:20.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:21:0)
[19:23:20.938] Timer tick, asking Buildbucket for the build status
[19:23:21.029] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:21.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:22:0) after 1m0s
[19:24:21.062] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:22:0)
[19:24:21.062] Timer tick, asking Buildbucket for the build status
[19:24:21.252] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:21.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:23:0) after 1m0s
[19:25:21.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:23:0)
[19:25:21.225] Timer tick, asking Buildbucket for the build status
[19:25:21.281] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:21.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:24:0) after 1m0s
[19:26:21.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:24:0)
[19:26:21.278] Timer tick, asking Buildbucket for the build status
[19:26:21.338] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:21.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:25:0) after 1m0s
[19:27:21.357] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:25:0)
[19:27:21.357] Timer tick, asking Buildbucket for the build status
[19:27:21.469] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:21.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:26:0) after 1m0s
[19:28:21.456] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:26:0)
[19:28:21.456] Timer tick, asking Buildbucket for the build status
[19:28:21.503] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:21.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:27:0) after 1m0s
[19:29:21.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:27:0)
[19:29:21.523] Timer tick, asking Buildbucket for the build status
[19:29:21.673] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:21.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:28:0) after 1m0s
[19:30:21.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:28:0)
[19:30:21.752] Timer tick, asking Buildbucket for the build status
[19:30:21.809] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:21.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:29:0) after 1m0s
[19:31:21.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:29:0)
[19:31:21.822] Timer tick, asking Buildbucket for the build status
[19:31:22.231] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:22.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:30:0) after 1m0s
[19:32:22.227] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:30:0)
[19:32:22.227] Timer tick, asking Buildbucket for the build status
[19:32:22.823] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:22.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:31:0) after 1m0s
[19:33:22.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:31:0)
[19:33:22.829] Timer tick, asking Buildbucket for the build status
[19:33:22.874] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:22.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:32:0) after 1m0s
[19:34:22.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:32:0)
[19:34:22.894] Timer tick, asking Buildbucket for the build status
[19:34:22.960] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:22.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:33:0) after 1m0s
[19:35:22.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:33:0)
[19:35:22.995] Timer tick, asking Buildbucket for the build status
[19:35:23.069] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:23.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:34:0) after 1m0s
[19:36:23.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:34:0)
[19:36:23.096] Timer tick, asking Buildbucket for the build status
[19:36:23.349] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:23.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:35:0) after 1m0s
[19:37:23.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:35:0)
[19:37:23.340] Timer tick, asking Buildbucket for the build status
[19:37:23.482] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:23.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:36:0) after 1m0s
[19:38:23.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:36:0)
[19:38:23.463] Timer tick, asking Buildbucket for the build status
[19:38:23.641] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:23.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:37:0) after 1m0s
[19:39:23.659] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:37:0)
[19:39:23.659] Timer tick, asking Buildbucket for the build status
[19:39:23.773] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:23.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:38:0) after 1m0s
[19:40:23.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:38:0)
[19:40:23.804] Timer tick, asking Buildbucket for the build status
[19:40:23.904] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:23.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:39:0) after 1m0s
[19:41:23.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:39:0)
[19:41:23.919] Timer tick, asking Buildbucket for the build status
[19:41:24.065] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:24.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:40:0) after 1m0s
[19:42:24.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:40:0)
[19:42:24.085] Timer tick, asking Buildbucket for the build status
[19:42:24.701] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:24.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:41:0) after 1m0s
[19:43:24.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:41:0)
[19:43:24.722] Timer tick, asking Buildbucket for the build status
[19:43:25.115] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:25.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:42:0) after 1m0s
[19:44:25.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:42:0)
[19:44:25.099] Timer tick, asking Buildbucket for the build status
[19:44:25.146] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:25.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:43:0) after 1m0s
[19:45:25.161] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:43:0)
[19:45:25.161] Timer tick, asking Buildbucket for the build status
[19:45:25.220] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:25.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:44:0) after 1m0s
[19:46:25.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:44:0)
[19:46:25.202] Timer tick, asking Buildbucket for the build status
[19:46:25.274] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:25.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:45:0) after 1m0s
[19:47:25.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:45:0)
[19:47:25.282] Timer tick, asking Buildbucket for the build status
[19:47:25.444] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:25.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:46:0) after 1m0s
[19:48:25.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:46:0)
[19:48:25.443] Timer tick, asking Buildbucket for the build status
[19:48:25.498] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:25.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:47:0) after 1m0s
[19:49:25.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:47:0)
[19:49:25.618] Timer tick, asking Buildbucket for the build status
[19:49:25.687] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:25.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:48:0) after 1m0s
[19:50:25.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:48:0)
[19:50:25.707] Timer tick, asking Buildbucket for the build status
[19:50:25.746] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:25.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:49:0) after 1m0s
[19:51:25.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:49:0)
[19:51:25.768] Timer tick, asking Buildbucket for the build status
[19:51:25.918] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:25.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:50:0) after 1m0s
[19:52:25.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:50:0)
[19:52:25.913] Timer tick, asking Buildbucket for the build status
[19:52:25.956] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:25.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:51:0) after 1m0s
[19:53:25.974] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:51:0)
[19:53:25.974] Timer tick, asking Buildbucket for the build status
[19:53:26.053] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:26.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:52:0) after 1m0s
[19:54:26.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:52:0)
[19:54:26.072] Timer tick, asking Buildbucket for the build status
[19:54:26.189] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:26.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:53:0) after 1m0s
[19:55:26.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:53:0)
[19:55:26.203] Timer tick, asking Buildbucket for the build status
[19:55:26.279] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:26.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:54:0) after 1m0s
[19:56:26.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:54:0)
[19:56:26.302] Timer tick, asking Buildbucket for the build status
[19:56:26.341] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:26.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:55:0) after 1m0s
[19:57:26.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:55:0)
[19:57:26.316] Timer tick, asking Buildbucket for the build status
[19:57:28.773] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:28.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:56:0) after 1m0s
[19:58:28.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:56:0)
[19:58:28.767] Timer tick, asking Buildbucket for the build status
[19:58:28.811] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:28.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:57:0) after 1m0s
[19:59:28.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:57:0)
[19:59:28.801] Timer tick, asking Buildbucket for the build status
[19:59:28.867] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:28.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:58:0) after 1m0s
[20:00:28.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:58:0)
[20:00:28.898] Timer tick, asking Buildbucket for the build status
[20:00:29.018] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:29.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:59:0) after 1m0s
[20:01:29.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:59:0)
[20:01:29.037] Timer tick, asking Buildbucket for the build status
[20:01:29.335] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:29.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:60:0) after 1m0s
[20:02:29.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:60:0)
[20:02:29.330] Timer tick, asking Buildbucket for the build status
[20:02:29.394] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:29.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:61:0) after 1m0s
[20:03:29.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:61:0)
[20:03:29.411] Timer tick, asking Buildbucket for the build status
[20:03:29.481] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:29.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:62:0) after 1m0s
[20:04:29.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:62:0)
[20:04:29.490] Timer tick, asking Buildbucket for the build status
[20:04:29.680] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:29.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:63:0) after 1m0s
[20:05:29.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:63:0)
[20:05:29.695] Timer tick, asking Buildbucket for the build status
[20:05:29.779] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:29.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:64:0) after 1m0s
[20:06:29.797] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:64:0)
[20:06:29.797] Timer tick, asking Buildbucket for the build status
[20:06:29.840] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:29.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:65:0) after 1m0s
[20:07:29.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:65:0)
[20:07:29.859] Timer tick, asking Buildbucket for the build status
[20:07:30.000] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:30.000] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:66:0) after 1m0s
[20:08:29.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:66:0)
[20:08:29.970] Timer tick, asking Buildbucket for the build status
[20:08:30.100] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:30.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:67:0) after 1m0s
[20:09:30.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:67:0)
[20:09:30.105] Timer tick, asking Buildbucket for the build status
[20:09:30.387] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:30.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:68:0) after 1m0s
[20:10:30.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:68:0)
[20:10:30.410] Timer tick, asking Buildbucket for the build status
[20:10:30.689] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:30.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:69:0) after 1m0s
[20:13:05.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:69:0)
[20:13:05.156] Timer tick, asking Buildbucket for the build status
[20:13:05.409] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:05.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:70:0) after 1m0s
[20:14:05.419] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:70:0)
[20:14:05.419] Timer tick, asking Buildbucket for the build status
[20:14:05.464] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:05.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:71:0) after 1m0s
[20:15:05.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:71:0)
[20:15:05.460] Timer tick, asking Buildbucket for the build status
[20:15:05.504] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:05.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:72:0) after 1m0s
[20:16:05.528] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:72:0)
[20:16:05.528] Timer tick, asking Buildbucket for the build status
[20:16:05.688] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:05.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:73:0) after 1m0s
[20:17:05.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:73:0)
[20:17:05.726] Timer tick, asking Buildbucket for the build status
[20:17:05.850] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:05.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:74:0) after 1m0s
[20:18:05.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:74:0)
[20:18:05.857] Timer tick, asking Buildbucket for the build status
[20:18:05.925] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:05.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:75:0) after 1m0s
[20:19:05.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:75:0)
[20:19:05.923] Timer tick, asking Buildbucket for the build status
[20:19:05.964] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:05.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:76:0) after 1m0s
[20:20:05.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:76:0)
[20:20:05.992] Timer tick, asking Buildbucket for the build status
[20:20:06.039] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:06.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:77:0) after 1m0s
[20:21:06.050] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:77:0)
[20:21:06.050] Timer tick, asking Buildbucket for the build status
[20:21:06.114] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:06.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:78:0) after 1m0s
[20:22:06.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:78:0)
[20:22:06.096] Timer tick, asking Buildbucket for the build status
[20:22:06.309] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:06.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:79:0) after 1m0s
[20:23:06.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:79:0)
[20:23:06.332] Timer tick, asking Buildbucket for the build status
[20:23:06.443] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:06.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:80:0) after 1m0s
[20:24:06.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:80:0)
[20:24:06.465] Timer tick, asking Buildbucket for the build status
[20:24:06.590] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:06.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:81:0) after 1m0s
[20:25:06.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:81:0)
[20:25:06.584] Timer tick, asking Buildbucket for the build status
[20:25:06.784] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:06.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:82:0) after 1m0s
[20:26:06.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:82:0)
[20:26:06.798] Timer tick, asking Buildbucket for the build status
[20:26:06.982] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:06.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:83:0) after 1m0s
[20:27:07.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:83:0)
[20:27:07.005] Timer tick, asking Buildbucket for the build status
[20:27:07.095] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:07.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:84:0) after 1m0s
[20:28:07.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:84:0)
[20:28:07.107] Timer tick, asking Buildbucket for the build status
[20:28:07.181] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:07.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:85:0) after 1m0s
[20:29:07.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:85:0)
[20:29:07.181] Timer tick, asking Buildbucket for the build status
[20:29:07.343] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:07.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:86:0) after 1m0s
[20:30:07.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:86:0)
[20:30:07.412] Timer tick, asking Buildbucket for the build status
[20:30:07.467] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:07.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:87:0) after 1m0s
[20:31:07.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:87:0)
[20:31:07.488] Timer tick, asking Buildbucket for the build status
[20:31:07.660] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:07.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:88:0) after 1m0s
[20:32:07.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:88:0)
[20:32:07.682] Timer tick, asking Buildbucket for the build status
[20:32:07.749] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:07.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:89:0) after 1m0s
[20:33:07.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:89:0)
[20:33:07.769] Timer tick, asking Buildbucket for the build status
[20:33:07.812] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:07.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:90:0) after 1m0s
[20:34:07.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:90:0)
[20:34:07.857] Timer tick, asking Buildbucket for the build status
[20:34:07.944] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:07.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:91:0) after 1m0s
[20:35:07.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:91:0)
[20:35:07.969] Timer tick, asking Buildbucket for the build status
[20:35:08.030] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:08.030] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:92:0) after 1m0s
[20:36:08.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:92:0)
[20:36:08.074] Timer tick, asking Buildbucket for the build status
[20:36:08.145] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:08.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:93:0) after 1m0s
[20:37:08.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:93:0)
[20:37:08.169] Timer tick, asking Buildbucket for the build status
[20:37:08.257] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:08.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:94:0) after 1m0s
[20:38:08.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:94:0)
[20:38:08.261] Timer tick, asking Buildbucket for the build status
[20:38:08.303] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:08.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:95:0) after 1m0s
[20:39:08.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:95:0)
[20:39:08.321] Timer tick, asking Buildbucket for the build status
[20:39:08.403] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:08.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:96:0) after 1m0s
[20:40:08.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:96:0)
[20:40:08.429] Timer tick, asking Buildbucket for the build status
[20:40:08.469] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:08.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:97:0) after 1m0s
[20:41:08.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:97:0)
[20:41:08.468] Timer tick, asking Buildbucket for the build status
[20:41:08.641] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:08.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:98:0) after 1m0s
[20:42:08.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:98:0)
[20:42:08.664] Timer tick, asking Buildbucket for the build status
[20:42:08.944] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:08.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:99:0) after 1m0s
[20:43:08.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:99:0)
[20:43:08.966] Timer tick, asking Buildbucket for the build status
[20:43:09.089] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:09.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:100:0) after 1m0s
[20:44:09.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:100:0)
[20:44:09.094] Timer tick, asking Buildbucket for the build status
[20:44:09.155] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:09.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:101:0) after 1m0s
[20:45:09.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:101:0)
[20:45:09.181] Timer tick, asking Buildbucket for the build status
[20:45:09.273] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:09.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:102:0) after 1m0s
[20:46:09.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:102:0)
[20:46:09.289] Timer tick, asking Buildbucket for the build status
[20:46:09.328] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:09.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:103:0) after 1m0s
[20:47:09.339] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:103:0)
[20:47:09.339] Timer tick, asking Buildbucket for the build status
[20:47:09.603] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:09.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:104:0) after 1m0s
[20:48:09.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:104:0)
[20:48:09.608] Timer tick, asking Buildbucket for the build status
[20:48:09.776] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:09.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:105:0) after 1m0s
[20:49:09.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:105:0)
[20:49:09.786] Timer tick, asking Buildbucket for the build status
[20:49:09.851] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:09.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:106:0) after 1m0s
[20:50:09.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:106:0)
[20:50:09.871] Timer tick, asking Buildbucket for the build status
[20:50:09.908] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:09.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:107:0) after 1m0s
[20:51:09.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:107:0)
[20:51:09.910] Timer tick, asking Buildbucket for the build status
[20:51:09.969] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:09.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:108:0) after 1m0s
[20:52:09.991] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:108:0)
[20:52:09.991] Timer tick, asking Buildbucket for the build status
[20:52:10.055] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:10.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:109:0) after 1m0s
[20:53:10.070] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:109:0)
[20:53:10.070] Timer tick, asking Buildbucket for the build status
[20:53:10.115] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:10.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:110:0) after 1m0s
[20:54:10.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:110:0)
[20:54:10.113] Timer tick, asking Buildbucket for the build status
[20:54:10.201] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:10.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:111:0) after 1m0s
[20:55:10.221] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:111:0)
[20:55:10.221] Timer tick, asking Buildbucket for the build status
[20:55:10.310] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:10.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:112:0) after 1m0s
[20:56:10.344] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:112:0)
[20:56:10.344] Timer tick, asking Buildbucket for the build status
[20:56:10.393] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:10.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:113:0) after 1m0s
[20:57:10.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:113:0)
[20:57:10.436] Timer tick, asking Buildbucket for the build status
[20:57:10.497] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:10.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:114:0) after 1m0s
[20:58:10.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:114:0)
[20:58:10.560] Timer tick, asking Buildbucket for the build status
[20:58:10.604] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:10.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:115:0) after 1m0s
[20:59:10.645] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:115:0)
[20:59:10.645] Timer tick, asking Buildbucket for the build status
[20:59:10.866] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:10.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:116:0) after 1m0s
[21:00:10.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:116:0)
[21:00:10.892] Timer tick, asking Buildbucket for the build status
[21:00:10.999] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:10.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:117:0) after 1m0s
[21:01:11.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:117:0)
[21:01:11.034] Timer tick, asking Buildbucket for the build status
[21:01:11.159] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:11.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:118:0) after 1m0s
[21:02:11.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:118:0)
[21:02:11.186] Timer tick, asking Buildbucket for the build status
[21:02:11.487] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:11.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:119:0) after 1m0s
[21:03:11.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:119:0)
[21:03:11.523] Timer tick, asking Buildbucket for the build status
[21:03:11.595] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:11.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:120:0) after 1m0s
[21:04:11.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:120:0)
[21:04:11.604] Timer tick, asking Buildbucket for the build status
[21:04:11.683] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:11.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:121:0) after 1m0s
[21:05:11.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:121:0)
[21:05:11.737] Timer tick, asking Buildbucket for the build status
[21:05:11.778] Build 8917988266938404176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:11.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083418328967300672:122:0) after 1m0s
[21:05:42.816] Received PubSub notification, asking Buildbucket for the build status
[21:05:42.903] Build 8917988266938404176: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:05:42.903] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553547942491905",
"created_by": "project:fuchsia",
"created_ts": "1553540658016004",
"failure_reason": "BUILD_FAILURE",
"id": "8917988266938404176",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"167af1bafbf0a2a9149f03315f7a30a8425db408\"}}",
"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-7887e815-us-central1-c-fwlf\", \"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\": \"167af1bafbf0a2a9149f03315f7a30a8425db408\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-7887e815-us-central1-c-fwlf\"], \"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\": [\"4216-cf4a3cd\"], \"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": "1553540696735470",
"status": "COMPLETED",
"status_changed_ts": "1553540697116393",
"tags": [
"builder:clang",
"buildset:commit/git/167af1bafbf0a2a9149f03315f7a30a8425db408",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/167af1bafbf0a2a9149f03315f7a30a8425db408",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083418328967300672",
"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/8917988266938404176/+/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:43cf0ecac2ea7110",
"user_agent:luci-scheduler"
],
"updated_ts": "1553547942555224",
"url": "https://ci.chromium.org/b/8917988266938404176",
"utcnow_ts": "1553547942891483"
}
}
[21:05:42.903] Invocation finished in 2h1m26.20503323s with status FAILED