[22:15:42.145] New invocation is queued and will start shortly
[22:15:43.227] Starting the invocation (attempt 1)
[22:15:43.262] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:15:43.262] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:15:43.262] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080507182699092672",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"76f64b665bb12b0097a71c10b21a1d302bc84229\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080507182699092672",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/76f64b665bb12b0097a71c10b21a1d302bc84229",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/76f64b665bb12b0097a71c10b21a1d302bc84229",
"gitiles_ref:refs/heads/master"
]
}
[22:15:43.836] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556316943372473",
"id": "8915077120745028768",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"76f64b665bb12b0097a71c10b21a1d302bc84229\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556316943671057",
"tags": [
"builder:clang",
"buildset:commit/git/76f64b665bb12b0097a71c10b21a1d302bc84229",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/76f64b665bb12b0097a71c10b21a1d302bc84229",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080507182699092672",
"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/8915077120745028768/+/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": "1556316943671151",
"url": "https://ci.chromium.org/b/8915077120745028768",
"utcnow_ts": "1556316943830303"
}
}
[22:15:43.836] Task URL: https://ci.chromium.org/b/8915077120745028768
[22:15:43.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:2:0) after 1m0s
[22:16:11.860] Received PubSub notification, asking Buildbucket for the build status
[22:16:11.936] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:43.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:2:0)
[22:16:43.878] Timer tick, asking Buildbucket for the build status
[22:16:44.037] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:44.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:4:0) after 1m0s
[22:17:44.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:4:0)
[22:17:44.013] Timer tick, asking Buildbucket for the build status
[22:17:44.272] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:44.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:5:0) after 1m0s
[22:18:44.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:5:0)
[22:18:44.265] Timer tick, asking Buildbucket for the build status
[22:18:44.308] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:44.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:6:0) after 1m0s
[22:19:44.349] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:6:0)
[22:19:44.350] Timer tick, asking Buildbucket for the build status
[22:19:44.390] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:44.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:7:0) after 1m0s
[22:20:44.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:7:0)
[22:20:44.412] Timer tick, asking Buildbucket for the build status
[22:20:44.494] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:44.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:8:0) after 1m0s
[22:21:44.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:8:0)
[22:21:44.470] Timer tick, asking Buildbucket for the build status
[22:21:44.510] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:44.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:9:0) after 1m0s
[22:22:44.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:9:0)
[22:22:44.533] Timer tick, asking Buildbucket for the build status
[22:22:44.580] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:44.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:10:0) after 1m0s
[22:23:44.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:10:0)
[22:23:44.597] Timer tick, asking Buildbucket for the build status
[22:23:44.763] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:44.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:11:0) after 1m0s
[22:24:44.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:11:0)
[22:24:44.774] Timer tick, asking Buildbucket for the build status
[22:24:44.851] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:44.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:12:0) after 1m0s
[22:25:44.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:12:0)
[22:25:44.921] Timer tick, asking Buildbucket for the build status
[22:25:44.999] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:44.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:13:0) after 1m0s
[22:26:45.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:13:0)
[22:26:45.009] Timer tick, asking Buildbucket for the build status
[22:26:45.143] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:45.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:14:0) after 1m0s
[22:27:45.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:14:0)
[22:27:45.164] Timer tick, asking Buildbucket for the build status
[22:27:45.232] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:45.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:15:0) after 1m0s
[22:28:45.235] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:15:0)
[22:28:45.235] Timer tick, asking Buildbucket for the build status
[22:28:45.272] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:45.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:16:0) after 1m0s
[22:29:45.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:16:0)
[22:29:45.296] Timer tick, asking Buildbucket for the build status
[22:29:45.409] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:45.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:17:0) after 1m0s
[22:30:45.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:17:0)
[22:30:45.476] Timer tick, asking Buildbucket for the build status
[22:30:45.698] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:45.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:18:0) after 1m0s
[22:31:45.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:18:0)
[22:31:45.716] Timer tick, asking Buildbucket for the build status
[22:31:45.857] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:45.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:19:0) after 1m0s
[22:32:45.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:19:0)
[22:32:45.876] Timer tick, asking Buildbucket for the build status
[22:32:45.922] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:45.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:20:0) after 1m0s
[22:33:45.940] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:20:0)
[22:33:45.940] Timer tick, asking Buildbucket for the build status
[22:33:46.088] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:46.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:21:0) after 1m0s
[22:34:46.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:21:0)
[22:34:46.097] Timer tick, asking Buildbucket for the build status
[22:34:46.132] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:46.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:22:0) after 1m0s
[22:35:46.130] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:22:0)
[22:35:46.130] Timer tick, asking Buildbucket for the build status
[22:35:46.201] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:46.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:23:0) after 1m0s
[22:36:46.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:23:0)
[22:36:46.212] Timer tick, asking Buildbucket for the build status
[22:36:46.355] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:46.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:24:0) after 1m0s
[22:37:46.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:24:0)
[22:37:46.343] Timer tick, asking Buildbucket for the build status
[22:37:46.491] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:46.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:25:0) after 1m0s
[22:38:46.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:25:0)
[22:38:46.513] Timer tick, asking Buildbucket for the build status
[22:38:46.564] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:46.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:26:0) after 1m0s
[22:39:46.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:26:0)
[22:39:46.583] Timer tick, asking Buildbucket for the build status
[22:39:46.765] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:46.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:27:0) after 1m0s
[22:40:46.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:27:0)
[22:40:46.771] Timer tick, asking Buildbucket for the build status
[22:40:46.833] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:46.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:28:0) after 1m0s
[22:41:46.855] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:28:0)
[22:41:46.855] Timer tick, asking Buildbucket for the build status
[22:41:46.938] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:46.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:29:0) after 1m0s
[22:42:46.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:29:0)
[22:42:46.957] Timer tick, asking Buildbucket for the build status
[22:42:47.087] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:47.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:30:0) after 1m0s
[22:43:47.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:30:0)
[22:43:47.109] Timer tick, asking Buildbucket for the build status
[22:43:47.175] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:47.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:31:0) after 1m0s
[22:44:47.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:31:0)
[22:44:47.211] Timer tick, asking Buildbucket for the build status
[22:44:47.251] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:47.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:32:0) after 1m0s
[22:45:47.313] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:32:0)
[22:45:47.313] Timer tick, asking Buildbucket for the build status
[22:45:47.349] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:47.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:33:0) after 1m0s
[22:46:47.357] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:33:0)
[22:46:47.357] Timer tick, asking Buildbucket for the build status
[22:46:47.413] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:47.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:34:0) after 1m0s
[22:47:47.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:34:0)
[22:47:47.423] Timer tick, asking Buildbucket for the build status
[22:47:47.467] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:47.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:35:0) after 1m0s
[22:48:47.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:35:0)
[22:48:47.486] Timer tick, asking Buildbucket for the build status
[22:48:47.534] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:47.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:36:0) after 1m0s
[22:49:47.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:36:0)
[22:49:47.533] Timer tick, asking Buildbucket for the build status
[22:49:47.667] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:47.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:37:0) after 1m0s
[22:50:47.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:37:0)
[22:50:47.693] Timer tick, asking Buildbucket for the build status
[22:50:47.777] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:47.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:38:0) after 1m0s
[22:51:47.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:38:0)
[22:51:47.798] Timer tick, asking Buildbucket for the build status
[22:51:47.855] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:47.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:39:0) after 1m0s
[22:52:47.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:39:0)
[22:52:47.877] Timer tick, asking Buildbucket for the build status
[22:52:47.999] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:47.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:40:0) after 1m0s
[22:53:48.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:40:0)
[22:53:48.072] Timer tick, asking Buildbucket for the build status
[22:53:48.257] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:48.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:41:0) after 1m0s
[22:54:48.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:41:0)
[22:54:48.264] Timer tick, asking Buildbucket for the build status
[22:54:48.335] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:48.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:42:0) after 1m0s
[22:55:48.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:42:0)
[22:55:48.349] Timer tick, asking Buildbucket for the build status
[22:55:48.390] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:48.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:43:0) after 1m0s
[22:56:48.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:43:0)
[22:56:48.393] Timer tick, asking Buildbucket for the build status
[22:56:48.601] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:48.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:44:0) after 1m0s
[22:57:48.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:44:0)
[22:57:48.610] Timer tick, asking Buildbucket for the build status
[22:57:48.757] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:48.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:45:0) after 1m0s
[22:58:48.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:45:0)
[22:58:48.774] Timer tick, asking Buildbucket for the build status
[22:58:48.887] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:48.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:46:0) after 1m0s
[22:59:48.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:46:0)
[22:59:48.884] Timer tick, asking Buildbucket for the build status
[22:59:49.047] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:49.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:47:0) after 1m0s
[23:00:49.020] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:47:0)
[23:00:49.020] Timer tick, asking Buildbucket for the build status
[23:00:49.066] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:49.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:48:0) after 1m0s
[23:01:49.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:48:0)
[23:01:49.089] Timer tick, asking Buildbucket for the build status
[23:01:49.278] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:49.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:49:0) after 1m0s
[23:02:49.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:49:0)
[23:02:49.297] Timer tick, asking Buildbucket for the build status
[23:02:49.353] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:49.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:50:0) after 1m0s
[23:03:49.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:50:0)
[23:03:49.393] Timer tick, asking Buildbucket for the build status
[23:03:49.762] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:49.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:51:0) after 1m0s
[23:04:49.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:51:0)
[23:04:49.796] Timer tick, asking Buildbucket for the build status
[23:04:49.876] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:49.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:52:0) after 1m0s
[23:05:49.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:52:0)
[23:05:49.850] Timer tick, asking Buildbucket for the build status
[23:05:49.957] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:49.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:53:0) after 1m0s
[23:06:49.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:53:0)
[23:06:49.995] Timer tick, asking Buildbucket for the build status
[23:06:50.099] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:50.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:54:0) after 1m0s
[23:07:50.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:54:0)
[23:07:50.122] Timer tick, asking Buildbucket for the build status
[23:07:50.159] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:50.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:55:0) after 1m0s
[23:08:50.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:55:0)
[23:08:50.180] Timer tick, asking Buildbucket for the build status
[23:08:50.366] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:50.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:56:0) after 1m0s
[23:09:50.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:56:0)
[23:09:50.390] Timer tick, asking Buildbucket for the build status
[23:09:50.432] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:50.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:57:0) after 1m0s
[23:10:50.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:57:0)
[23:10:50.434] Timer tick, asking Buildbucket for the build status
[23:10:50.514] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:50.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:58:0) after 1m0s
[23:11:50.535] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:58:0)
[23:11:50.535] Timer tick, asking Buildbucket for the build status
[23:11:50.815] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:50.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:59:0) after 1m0s
[23:12:50.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:59:0)
[23:12:50.896] Timer tick, asking Buildbucket for the build status
[23:12:50.951] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:50.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:60:0) after 1m0s
[23:13:50.977] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:60:0)
[23:13:50.977] Timer tick, asking Buildbucket for the build status
[23:13:51.110] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:51.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:61:0) after 1m0s
[23:14:51.132] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:61:0)
[23:14:51.132] Timer tick, asking Buildbucket for the build status
[23:14:51.186] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:51.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:62:0) after 1m0s
[23:15:51.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:62:0)
[23:15:51.207] Timer tick, asking Buildbucket for the build status
[23:15:51.266] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:51.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:63:0) after 1m0s
[23:16:51.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:63:0)
[23:16:51.287] Timer tick, asking Buildbucket for the build status
[23:16:51.342] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:51.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:64:0) after 1m0s
[23:17:51.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:64:0)
[23:17:51.361] Timer tick, asking Buildbucket for the build status
[23:17:51.504] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:51.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:65:0) after 1m0s
[23:18:51.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:65:0)
[23:18:51.525] Timer tick, asking Buildbucket for the build status
[23:18:51.590] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:51.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:66:0) after 1m0s
[23:19:51.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:66:0)
[23:19:51.628] Timer tick, asking Buildbucket for the build status
[23:19:51.674] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:51.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:67:0) after 1m0s
[23:20:51.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:67:0)
[23:20:51.685] Timer tick, asking Buildbucket for the build status
[23:20:51.820] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:51.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:68:0) after 1m0s
[23:21:51.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:68:0)
[23:21:51.826] Timer tick, asking Buildbucket for the build status
[23:21:51.873] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:51.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:69:0) after 1m0s
[23:22:51.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:69:0)
[23:22:51.897] Timer tick, asking Buildbucket for the build status
[23:22:52.067] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:52.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:70:0) after 1m0s
[23:23:52.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:70:0)
[23:23:52.087] Timer tick, asking Buildbucket for the build status
[23:23:52.127] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:52.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:71:0) after 1m0s
[23:24:52.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:71:0)
[23:24:52.128] Timer tick, asking Buildbucket for the build status
[23:24:52.290] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:52.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:72:0) after 1m0s
[23:25:52.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:72:0)
[23:25:52.318] Timer tick, asking Buildbucket for the build status
[23:25:52.399] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:52.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:73:0) after 1m0s
[23:26:52.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:73:0)
[23:26:52.405] Timer tick, asking Buildbucket for the build status
[23:26:52.599] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:52.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:74:0) after 1m0s
[23:27:52.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:74:0)
[23:27:52.581] Timer tick, asking Buildbucket for the build status
[23:27:52.699] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:52.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:75:0) after 1m0s
[23:28:52.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:75:0)
[23:28:52.720] Timer tick, asking Buildbucket for the build status
[23:28:52.778] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:52.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:76:0) after 1m0s
[23:29:52.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:76:0)
[23:29:52.779] Timer tick, asking Buildbucket for the build status
[23:29:52.815] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:52.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:77:0) after 1m0s
[23:30:52.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:77:0)
[23:30:52.822] Timer tick, asking Buildbucket for the build status
[23:30:52.869] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:52.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:78:0) after 1m0s
[23:31:52.858] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:78:0)
[23:31:52.858] Timer tick, asking Buildbucket for the build status
[23:31:52.895] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:52.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:79:0) after 1m0s
[23:32:52.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:79:0)
[23:32:52.876] Timer tick, asking Buildbucket for the build status
[23:32:53.145] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:53.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:80:0) after 1m0s
[23:33:53.170] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:80:0)
[23:33:53.170] Timer tick, asking Buildbucket for the build status
[23:33:53.218] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:53.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:81:0) after 1m0s
[23:34:53.244] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:81:0)
[23:34:53.244] Timer tick, asking Buildbucket for the build status
[23:34:53.279] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:53.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:82:0) after 1m0s
[23:35:53.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:82:0)
[23:35:53.294] Timer tick, asking Buildbucket for the build status
[23:35:53.333] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:53.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:83:0) after 1m0s
[23:36:53.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:83:0)
[23:36:53.325] Timer tick, asking Buildbucket for the build status
[23:36:53.365] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:53.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:84:0) after 1m0s
[23:37:53.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:84:0)
[23:37:53.389] Timer tick, asking Buildbucket for the build status
[23:37:53.475] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:53.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:85:0) after 1m0s
[23:38:53.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:85:0)
[23:38:53.497] Timer tick, asking Buildbucket for the build status
[23:38:53.628] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:53.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:86:0) after 1m0s
[23:39:53.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:86:0)
[23:39:53.661] Timer tick, asking Buildbucket for the build status
[23:39:53.704] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:53.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:87:0) after 1m0s
[23:40:53.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:87:0)
[23:40:53.728] Timer tick, asking Buildbucket for the build status
[23:40:53.769] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:53.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:88:0) after 1m0s
[23:41:53.759] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:88:0)
[23:41:53.759] Timer tick, asking Buildbucket for the build status
[23:41:53.856] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:53.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:89:0) after 1m0s
[23:42:53.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:89:0)
[23:42:53.883] Timer tick, asking Buildbucket for the build status
[23:42:53.926] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:53.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:90:0) after 1m0s
[23:43:53.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:90:0)
[23:43:53.899] Timer tick, asking Buildbucket for the build status
[23:43:53.979] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:53.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:91:0) after 1m0s
[23:44:54.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:91:0)
[23:44:54.002] Timer tick, asking Buildbucket for the build status
[23:44:54.043] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:54.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:92:0) after 1m0s
[23:45:54.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:92:0)
[23:45:54.045] Timer tick, asking Buildbucket for the build status
[23:45:54.178] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:54.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:93:0) after 1m0s
[23:46:54.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:93:0)
[23:46:54.169] Timer tick, asking Buildbucket for the build status
[23:46:54.300] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:54.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:94:0) after 1m0s
[23:47:54.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:94:0)
[23:47:54.323] Timer tick, asking Buildbucket for the build status
[23:47:54.407] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:54.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:95:0) after 1m0s
[23:48:54.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:95:0)
[23:48:54.434] Timer tick, asking Buildbucket for the build status
[23:48:54.576] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:54.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:96:0) after 1m0s
[23:49:54.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:96:0)
[23:49:54.608] Timer tick, asking Buildbucket for the build status
[23:49:54.650] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:54.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:97:0) after 1m0s
[23:50:54.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:97:0)
[23:50:54.640] Timer tick, asking Buildbucket for the build status
[23:50:54.681] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:54.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:98:0) after 1m0s
[23:51:54.702] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:98:0)
[23:51:54.702] Timer tick, asking Buildbucket for the build status
[23:51:54.756] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:54.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:99:0) after 1m0s
[23:52:54.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:99:0)
[23:52:54.731] Timer tick, asking Buildbucket for the build status
[23:52:54.844] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:54.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:100:0) after 1m0s
[23:53:54.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:100:0)
[23:53:54.869] Timer tick, asking Buildbucket for the build status
[23:53:54.912] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:54.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:101:0) after 1m0s
[23:54:54.937] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:101:0)
[23:54:54.937] Timer tick, asking Buildbucket for the build status
[23:54:54.973] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:54.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:102:0) after 1m0s
[23:55:55.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:102:0)
[23:55:55.005] Timer tick, asking Buildbucket for the build status
[23:55:55.259] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:55.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:103:0) after 1m0s
[23:56:55.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:103:0)
[23:56:55.306] Timer tick, asking Buildbucket for the build status
[23:56:55.448] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:55.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:104:0) after 1m0s
[23:57:55.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:104:0)
[23:57:55.471] Timer tick, asking Buildbucket for the build status
[23:57:55.556] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:55.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:105:0) after 1m0s
[23:58:55.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:105:0)
[23:58:55.560] Timer tick, asking Buildbucket for the build status
[23:58:55.612] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:55.612] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:106:0) after 1m0s
[23:59:55.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:106:0)
[23:59:55.594] Timer tick, asking Buildbucket for the build status
[23:59:55.656] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:55.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:107:0) after 1m0s
[00:00:55.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:107:0)
[00:00:55.677] Timer tick, asking Buildbucket for the build status
[00:00:55.718] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:55.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:108:0) after 1m0s
[00:01:55.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:108:0)
[00:01:55.742] Timer tick, asking Buildbucket for the build status
[00:01:55.842] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:55.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:109:0) after 1m0s
[00:02:55.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:109:0)
[00:02:55.869] Timer tick, asking Buildbucket for the build status
[00:02:55.908] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:55.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:110:0) after 1m0s
[00:03:55.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:110:0)
[00:03:55.886] Timer tick, asking Buildbucket for the build status
[00:03:56.051] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:56.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:111:0) after 1m0s
[00:04:56.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:111:0)
[00:04:56.073] Timer tick, asking Buildbucket for the build status
[00:04:56.112] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:56.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:112:0) after 1m0s
[00:05:56.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:112:0)
[00:05:56.136] Timer tick, asking Buildbucket for the build status
[00:05:56.218] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:56.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:113:0) after 1m0s
[00:06:56.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:113:0)
[00:06:56.247] Timer tick, asking Buildbucket for the build status
[00:06:56.287] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:56.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:114:0) after 1m0s
[00:07:56.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:114:0)
[00:07:56.319] Timer tick, asking Buildbucket for the build status
[00:07:56.674] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:56.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:115:0) after 1m0s
[00:08:56.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:115:0)
[00:08:56.699] Timer tick, asking Buildbucket for the build status
[00:08:57.084] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:57.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:116:0) after 1m0s
[00:09:57.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:116:0)
[00:09:57.095] Timer tick, asking Buildbucket for the build status
[00:09:57.164] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:57.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:117:0) after 1m0s
[00:10:57.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:117:0)
[00:10:57.145] Timer tick, asking Buildbucket for the build status
[00:10:57.369] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:57.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:118:0) after 1m0s
[00:11:57.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:118:0)
[00:11:57.401] Timer tick, asking Buildbucket for the build status
[00:11:57.448] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:57.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:119:0) after 1m0s
[00:12:57.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:119:0)
[00:12:57.480] Timer tick, asking Buildbucket for the build status
[00:12:57.590] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:57.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:120:0) after 1m0s
[00:13:57.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:120:0)
[00:13:57.612] Timer tick, asking Buildbucket for the build status
[00:13:57.673] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:57.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:121:0) after 1m0s
[00:14:57.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:121:0)
[00:14:57.696] Timer tick, asking Buildbucket for the build status
[00:14:57.760] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:57.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:122:0) after 1m0s
[00:15:57.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:122:0)
[00:15:57.786] Timer tick, asking Buildbucket for the build status
[00:15:58.832] Build 8915077120745028768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:58.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080507182699092672:123:0) after 1m0s
[00:16:47.038] Received PubSub notification, asking Buildbucket for the build status
[00:16:47.085] Build 8915077120745028768: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:16:47.085] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556324206632807",
"created_by": "project:fuchsia",
"created_ts": "1556316943372473",
"failure_reason": "BUILD_FAILURE",
"id": "8915077120745028768",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"76f64b665bb12b0097a71c10b21a1d302bc84229\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-xvm3\", \"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\": \"76f64b665bb12b0097a71c10b21a1d302bc84229\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-xvm3\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4274-a0a5f8c\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1556316970909354",
"status": "COMPLETED",
"status_changed_ts": "1556316971616467",
"tags": [
"builder:clang",
"buildset:commit/git/76f64b665bb12b0097a71c10b21a1d302bc84229",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/76f64b665bb12b0097a71c10b21a1d302bc84229",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080507182699092672",
"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/8915077120745028768/+/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:4474897ec8313410",
"user_agent:luci-scheduler"
],
"updated_ts": "1556324206692042",
"url": "https://ci.chromium.org/b/8915077120745028768",
"utcnow_ts": "1556324207075819"
}
}
[00:16:47.085] Invocation finished in 2h1m4.959654033s with status FAILED