[20:05:56.794] New invocation is queued and will start shortly
[20:05:57.893] Starting the invocation (attempt 1)
[20:05:57.960] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:05:57.960] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:05:57.961] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079246988710834832",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"c0fe5eb39c85756e02d1bf3e05127bc7e55a8973\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079246988710834832",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/c0fe5eb39c85756e02d1bf3e05127bc7e55a8973",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/c0fe5eb39c85756e02d1bf3e05127bc7e55a8973",
"gitiles_ref:refs/heads/master"
]
}
[20:05:58.689] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557518758101216",
"id": "8913816926664003040",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"c0fe5eb39c85756e02d1bf3e05127bc7e55a8973\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557518758522078",
"tags": [
"builder:clang",
"buildset:commit/git/c0fe5eb39c85756e02d1bf3e05127bc7e55a8973",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/c0fe5eb39c85756e02d1bf3e05127bc7e55a8973",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079246988710834832",
"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/8913816926664003040/+/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": "1557518758522201",
"url": "https://ci.chromium.org/b/8913816926664003040",
"utcnow_ts": "1557518758680233"
}
}
[20:05:58.689] Task URL: https://ci.chromium.org/b/8913816926664003040
[20:05:58.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:2:0) after 1m0s
[20:06:08.851] Received PubSub notification, asking Buildbucket for the build status
[20:06:09.070] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:58.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:2:0)
[20:06:58.706] Timer tick, asking Buildbucket for the build status
[20:06:59.773] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:59.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:4:0) after 1m0s
[20:07:59.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:4:0)
[20:07:59.821] Timer tick, asking Buildbucket for the build status
[20:07:59.935] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:59.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:5:0) after 1m0s
[20:08:59.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:5:0)
[20:08:59.976] Timer tick, asking Buildbucket for the build status
[20:09:00.190] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:00.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:6:0) after 1m0s
[20:10:00.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:6:0)
[20:10:00.194] Timer tick, asking Buildbucket for the build status
[20:10:00.264] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:00.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:7:0) after 1m0s
[20:11:00.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:7:0)
[20:11:00.257] Timer tick, asking Buildbucket for the build status
[20:11:00.372] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:00.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:8:0) after 1m0s
[20:12:00.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:8:0)
[20:12:00.391] Timer tick, asking Buildbucket for the build status
[20:12:00.436] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:00.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:9:0) after 1m0s
[20:13:00.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:9:0)
[20:13:00.458] Timer tick, asking Buildbucket for the build status
[20:13:00.527] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:00.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:10:0) after 1m0s
[20:14:00.510] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:10:0)
[20:14:00.510] Timer tick, asking Buildbucket for the build status
[20:14:00.636] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:00.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:11:0) after 1m0s
[20:15:00.658] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:11:0)
[20:15:00.658] Timer tick, asking Buildbucket for the build status
[20:15:00.758] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:00.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:12:0) after 1m0s
[20:16:00.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:12:0)
[20:16:00.780] Timer tick, asking Buildbucket for the build status
[20:16:00.883] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:00.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:13:0) after 1m0s
[20:17:00.954] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:13:0)
[20:17:00.954] Timer tick, asking Buildbucket for the build status
[20:17:01.020] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:01.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:14:0) after 1m0s
[20:18:01.039] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:14:0)
[20:18:01.039] Timer tick, asking Buildbucket for the build status
[20:18:01.389] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:01.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:15:0) after 1m0s
[20:19:01.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:15:0)
[20:19:01.429] Timer tick, asking Buildbucket for the build status
[20:19:01.487] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:01.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:16:0) after 1m0s
[20:20:01.510] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:16:0)
[20:20:01.510] Timer tick, asking Buildbucket for the build status
[20:20:01.582] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:01.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:17:0) after 1m0s
[20:21:01.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:17:0)
[20:21:01.607] Timer tick, asking Buildbucket for the build status
[20:21:01.778] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:01.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:18:0) after 1m0s
[20:22:01.788] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:18:0)
[20:22:01.788] Timer tick, asking Buildbucket for the build status
[20:22:01.849] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:01.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:19:0) after 1m0s
[20:23:01.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:19:0)
[20:23:01.850] Timer tick, asking Buildbucket for the build status
[20:23:01.921] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:01.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:20:0) after 1m0s
[20:24:01.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:20:0)
[20:24:01.943] Timer tick, asking Buildbucket for the build status
[20:24:02.157] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:02.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:21:0) after 1m0s
[20:25:02.137] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:21:0)
[20:25:02.137] Timer tick, asking Buildbucket for the build status
[20:25:02.427] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:02.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:22:0) after 1m0s
[20:26:02.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:22:0)
[20:26:02.417] Timer tick, asking Buildbucket for the build status
[20:26:02.511] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:02.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:23:0) after 1m0s
[20:27:02.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:23:0)
[20:27:02.540] Timer tick, asking Buildbucket for the build status
[20:27:02.597] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:02.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:24:0) after 1m0s
[20:28:02.649] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:24:0)
[20:28:02.649] Timer tick, asking Buildbucket for the build status
[20:28:02.746] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:02.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:25:0) after 1m0s
[20:29:02.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:25:0)
[20:29:02.782] Timer tick, asking Buildbucket for the build status
[20:29:02.880] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:02.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:26:0) after 1m0s
[20:30:03.039] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:26:0)
[20:30:03.039] Timer tick, asking Buildbucket for the build status
[20:30:03.422] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:03.422] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:27:0) after 1m0s
[20:31:03.409] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:27:0)
[20:31:03.409] Timer tick, asking Buildbucket for the build status
[20:31:03.890] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:03.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:28:0) after 1m0s
[20:32:04.056] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:28:0)
[20:32:04.056] Timer tick, asking Buildbucket for the build status
[20:32:04.184] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:04.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:29:0) after 1m0s
[20:33:04.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:29:0)
[20:33:04.182] Timer tick, asking Buildbucket for the build status
[20:33:04.283] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:04.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:30:0) after 1m0s
[20:34:04.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:30:0)
[20:34:04.295] Timer tick, asking Buildbucket for the build status
[20:34:04.464] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:04.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:31:0) after 1m0s
[20:35:04.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:31:0)
[20:35:04.485] Timer tick, asking Buildbucket for the build status
[20:35:04.710] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:04.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:32:0) after 1m0s
[20:36:04.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:32:0)
[20:36:04.782] Timer tick, asking Buildbucket for the build status
[20:36:04.827] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:04.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:33:0) after 1m0s
[20:37:04.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:33:0)
[20:37:04.823] Timer tick, asking Buildbucket for the build status
[20:37:05.081] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:05.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:34:0) after 1m0s
[20:38:05.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:34:0)
[20:38:05.104] Timer tick, asking Buildbucket for the build status
[20:38:05.189] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:05.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:35:0) after 1m0s
[20:39:05.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:35:0)
[20:39:05.183] Timer tick, asking Buildbucket for the build status
[20:39:06.907] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:06.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:36:0) after 1m0s
[20:40:06.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:36:0)
[20:40:06.980] Timer tick, asking Buildbucket for the build status
[20:40:07.158] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:07.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:37:0) after 1m0s
[20:41:07.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:37:0)
[20:41:07.312] Timer tick, asking Buildbucket for the build status
[20:41:07.387] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:07.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:38:0) after 1m0s
[20:42:07.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:38:0)
[20:42:07.388] Timer tick, asking Buildbucket for the build status
[20:42:07.465] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:07.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:39:0) after 1m0s
[20:43:07.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:39:0)
[20:43:07.492] Timer tick, asking Buildbucket for the build status
[20:43:07.657] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:07.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:40:0) after 1m0s
[20:44:07.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:40:0)
[20:44:07.703] Timer tick, asking Buildbucket for the build status
[20:44:07.842] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:07.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:41:0) after 1m0s
[20:45:07.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:41:0)
[20:45:07.882] Timer tick, asking Buildbucket for the build status
[20:45:07.938] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:07.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:42:0) after 1m0s
[20:46:07.960] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:42:0)
[20:46:07.960] Timer tick, asking Buildbucket for the build status
[20:46:08.347] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:08.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:43:0) after 1m0s
[20:47:08.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:43:0)
[20:47:08.368] Timer tick, asking Buildbucket for the build status
[20:47:08.435] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:08.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:44:0) after 1m0s
[20:48:08.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:44:0)
[20:48:08.458] Timer tick, asking Buildbucket for the build status
[20:48:18.268] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:18.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:45:0) after 1m0s
[20:49:18.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:45:0)
[20:49:18.261] Timer tick, asking Buildbucket for the build status
[20:49:18.397] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:18.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:46:0) after 1m0s
[20:50:18.419] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:46:0)
[20:50:18.419] Timer tick, asking Buildbucket for the build status
[20:50:19.270] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:19.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:47:0) after 1m0s
[20:51:19.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:47:0)
[20:51:19.264] Timer tick, asking Buildbucket for the build status
[20:51:19.328] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:19.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:48:0) after 1m0s
[20:52:19.350] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:48:0)
[20:52:19.350] Timer tick, asking Buildbucket for the build status
[20:52:19.402] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:19.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:49:0) after 1m0s
[20:53:19.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:49:0)
[20:53:19.377] Timer tick, asking Buildbucket for the build status
[20:53:19.436] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:19.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:50:0) after 1m0s
[20:54:19.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:50:0)
[20:54:19.448] Timer tick, asking Buildbucket for the build status
[20:54:19.544] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:19.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:51:0) after 1m0s
[20:55:19.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:51:0)
[20:55:19.569] Timer tick, asking Buildbucket for the build status
[20:55:19.646] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:19.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:52:0) after 1m0s
[20:56:19.670] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:52:0)
[20:56:19.670] Timer tick, asking Buildbucket for the build status
[20:56:19.711] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:19.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:53:0) after 1m0s
[20:57:19.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:53:0)
[20:57:19.741] Timer tick, asking Buildbucket for the build status
[20:57:19.986] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:19.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:54:0) after 1m0s
[20:58:20.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:54:0)
[20:58:20.016] Timer tick, asking Buildbucket for the build status
[20:58:20.166] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:20.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:55:0) after 1m0s
[20:59:20.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:55:0)
[20:59:20.182] Timer tick, asking Buildbucket for the build status
[20:59:20.241] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:20.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:56:0) after 1m0s
[21:00:20.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:56:0)
[21:00:20.296] Timer tick, asking Buildbucket for the build status
[21:00:20.445] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:20.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:57:0) after 1m0s
[21:01:20.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:57:0)
[21:01:20.428] Timer tick, asking Buildbucket for the build status
[21:01:20.596] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:20.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:58:0) after 1m0s
[21:02:20.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:58:0)
[21:02:20.583] Timer tick, asking Buildbucket for the build status
[21:02:20.688] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:20.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:59:0) after 1m0s
[21:03:20.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:59:0)
[21:03:20.765] Timer tick, asking Buildbucket for the build status
[21:03:20.858] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:20.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:60:0) after 1m0s
[21:04:20.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:60:0)
[21:04:20.887] Timer tick, asking Buildbucket for the build status
[21:04:20.988] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:20.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:61:0) after 1m0s
[21:05:21.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:61:0)
[21:05:21.027] Timer tick, asking Buildbucket for the build status
[21:05:21.240] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:21.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:62:0) after 1m0s
[21:06:21.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:62:0)
[21:06:21.346] Timer tick, asking Buildbucket for the build status
[21:06:21.390] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:21.390] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:63:0) after 1m0s
[21:07:21.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:63:0)
[21:07:21.410] Timer tick, asking Buildbucket for the build status
[21:07:21.475] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:21.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:64:0) after 1m0s
[21:08:21.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:64:0)
[21:08:21.515] Timer tick, asking Buildbucket for the build status
[21:08:21.572] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:21.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:65:0) after 1m0s
[21:09:21.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:65:0)
[21:09:21.591] Timer tick, asking Buildbucket for the build status
[21:09:21.756] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:21.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:66:0) after 1m0s
[21:10:21.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:66:0)
[21:10:21.777] Timer tick, asking Buildbucket for the build status
[21:10:21.825] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:21.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:67:0) after 1m0s
[21:11:21.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:67:0)
[21:11:21.852] Timer tick, asking Buildbucket for the build status
[21:11:22.024] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:22.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:68:0) after 1m0s
[21:12:22.024] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:68:0)
[21:12:22.024] Timer tick, asking Buildbucket for the build status
[21:12:22.108] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:22.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:69:0) after 1m0s
[21:13:22.143] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:69:0)
[21:13:22.143] Timer tick, asking Buildbucket for the build status
[21:13:22.274] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:22.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:70:0) after 1m0s
[21:14:22.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:70:0)
[21:14:22.297] Timer tick, asking Buildbucket for the build status
[21:14:22.346] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:22.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:71:0) after 1m0s
[21:15:22.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:71:0)
[21:15:22.403] Timer tick, asking Buildbucket for the build status
[21:15:22.528] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:22.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:72:0) after 1m0s
[21:16:22.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:72:0)
[21:16:22.548] Timer tick, asking Buildbucket for the build status
[21:16:22.682] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:22.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:73:0) after 1m0s
[21:17:22.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:73:0)
[21:17:22.703] Timer tick, asking Buildbucket for the build status
[21:17:22.748] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:22.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:74:0) after 1m0s
[21:18:22.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:74:0)
[21:18:22.751] Timer tick, asking Buildbucket for the build status
[21:18:22.827] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:22.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:75:0) after 1m0s
[21:19:22.851] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:75:0)
[21:19:22.851] Timer tick, asking Buildbucket for the build status
[21:19:23.004] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:23.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:76:0) after 1m0s
[21:20:23.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:76:0)
[21:20:23.047] Timer tick, asking Buildbucket for the build status
[21:20:23.126] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:23.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:77:0) after 1m0s
[21:21:23.125] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:77:0)
[21:21:23.125] Timer tick, asking Buildbucket for the build status
[21:21:23.361] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:23.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:78:0) after 1m0s
[21:22:23.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:78:0)
[21:22:23.398] Timer tick, asking Buildbucket for the build status
[21:22:23.454] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:23.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:79:0) after 1m0s
[21:23:23.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:79:0)
[21:23:23.448] Timer tick, asking Buildbucket for the build status
[21:23:23.504] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:23.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:80:0) after 1m0s
[21:24:23.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:80:0)
[21:24:23.511] Timer tick, asking Buildbucket for the build status
[21:24:23.559] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:23.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:81:0) after 1m0s
[21:25:23.570] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:81:0)
[21:25:23.570] Timer tick, asking Buildbucket for the build status
[21:25:23.974] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:23.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:82:0) after 1m0s
[21:26:23.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:82:0)
[21:26:23.985] Timer tick, asking Buildbucket for the build status
[21:26:24.503] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:24.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:83:0) after 1m0s
[21:27:24.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:83:0)
[21:27:24.478] Timer tick, asking Buildbucket for the build status
[21:27:25.125] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:25.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:84:0) after 1m0s
[21:28:25.158] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:84:0)
[21:28:25.158] Timer tick, asking Buildbucket for the build status
[21:28:25.221] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:25.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:85:0) after 1m0s
[21:29:25.220] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:85:0)
[21:29:25.220] Timer tick, asking Buildbucket for the build status
[21:29:25.294] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:25.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:86:0) after 1m0s
[21:30:25.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:86:0)
[21:30:25.318] Timer tick, asking Buildbucket for the build status
[21:30:25.487] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:25.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:87:0) after 1m0s
[21:31:25.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:87:0)
[21:31:25.539] Timer tick, asking Buildbucket for the build status
[21:31:25.759] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:25.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:88:0) after 1m0s
[21:32:25.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:88:0)
[21:32:25.950] Timer tick, asking Buildbucket for the build status
[21:32:26.256] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:26.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:89:0) after 1m0s
[21:33:26.288] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:89:0)
[21:33:26.288] Timer tick, asking Buildbucket for the build status
[21:33:26.360] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:26.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:90:0) after 1m0s
[21:34:26.337] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:90:0)
[21:34:26.337] Timer tick, asking Buildbucket for the build status
[21:34:26.436] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:26.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:91:0) after 1m0s
[21:35:26.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:91:0)
[21:35:26.415] Timer tick, asking Buildbucket for the build status
[21:35:26.473] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:26.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:92:0) after 1m0s
[21:36:26.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:92:0)
[21:36:26.496] Timer tick, asking Buildbucket for the build status
[21:36:26.599] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:26.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:93:0) after 1m0s
[21:37:26.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:93:0)
[21:37:26.603] Timer tick, asking Buildbucket for the build status
[21:37:26.706] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:26.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:94:0) after 1m0s
[21:38:26.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:94:0)
[21:38:26.730] Timer tick, asking Buildbucket for the build status
[21:38:26.864] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:26.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:95:0) after 1m0s
[21:39:26.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:95:0)
[21:39:26.934] Timer tick, asking Buildbucket for the build status
[21:39:27.024] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:27.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:96:0) after 1m0s
[21:40:27.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:96:0)
[21:40:27.002] Timer tick, asking Buildbucket for the build status
[21:40:27.083] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:27.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:97:0) after 1m0s
[21:41:27.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:97:0)
[21:41:27.115] Timer tick, asking Buildbucket for the build status
[21:41:27.192] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:27.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:98:0) after 1m0s
[21:42:27.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:98:0)
[21:42:27.204] Timer tick, asking Buildbucket for the build status
[21:42:27.267] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:27.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:99:0) after 1m0s
[21:43:27.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:99:0)
[21:43:27.298] Timer tick, asking Buildbucket for the build status
[21:43:27.372] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:27.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:100:0) after 1m0s
[21:44:27.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:100:0)
[21:44:27.395] Timer tick, asking Buildbucket for the build status
[21:44:27.714] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:27.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:101:0) after 1m0s
[21:45:27.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:101:0)
[21:45:27.703] Timer tick, asking Buildbucket for the build status
[21:45:27.818] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:27.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:102:0) after 1m0s
[21:46:27.842] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:102:0)
[21:46:27.842] Timer tick, asking Buildbucket for the build status
[21:46:28.012] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:28.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:103:0) after 1m0s
[21:47:28.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:103:0)
[21:47:28.019] Timer tick, asking Buildbucket for the build status
[21:47:28.104] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:28.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:104:0) after 1m0s
[21:48:28.142] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:104:0)
[21:48:28.142] Timer tick, asking Buildbucket for the build status
[21:48:28.222] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:28.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:105:0) after 1m0s
[21:49:28.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:105:0)
[21:49:28.248] Timer tick, asking Buildbucket for the build status
[21:49:29.217] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:29.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:106:0) after 1m0s
[21:50:29.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:106:0)
[21:50:29.206] Timer tick, asking Buildbucket for the build status
[21:50:29.467] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:29.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:107:0) after 1m0s
[21:51:29.444] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:107:0)
[21:51:29.444] Timer tick, asking Buildbucket for the build status
[21:51:29.638] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:29.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:108:0) after 1m0s
[21:52:29.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:108:0)
[21:52:29.661] Timer tick, asking Buildbucket for the build status
[21:52:29.990] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:29.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:109:0) after 1m0s
[21:53:30.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:109:0)
[21:53:30.019] Timer tick, asking Buildbucket for the build status
[21:53:30.086] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:30.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:110:0) after 1m0s
[21:54:30.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:110:0)
[21:54:30.096] Timer tick, asking Buildbucket for the build status
[21:54:30.164] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:30.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:111:0) after 1m0s
[21:55:30.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:111:0)
[21:55:30.201] Timer tick, asking Buildbucket for the build status
[21:55:30.283] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:30.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:112:0) after 1m0s
[21:56:30.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:112:0)
[21:56:30.301] Timer tick, asking Buildbucket for the build status
[21:56:30.369] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:30.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:113:0) after 1m0s
[21:57:30.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:113:0)
[21:57:30.369] Timer tick, asking Buildbucket for the build status
[21:57:30.587] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:30.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:114:0) after 1m0s
[21:58:30.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:114:0)
[21:58:30.559] Timer tick, asking Buildbucket for the build status
[21:58:30.620] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:30.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:115:0) after 1m0s
[21:59:30.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:115:0)
[21:59:30.679] Timer tick, asking Buildbucket for the build status
[21:59:30.727] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:30.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:116:0) after 1m0s
[22:00:30.744] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:116:0)
[22:00:30.744] Timer tick, asking Buildbucket for the build status
[22:00:31.044] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:31.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:117:0) after 1m0s
[22:01:31.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:117:0)
[22:01:31.086] Timer tick, asking Buildbucket for the build status
[22:01:31.212] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:31.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:118:0) after 1m0s
[22:02:31.239] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:118:0)
[22:02:31.239] Timer tick, asking Buildbucket for the build status
[22:02:31.371] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:31.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:119:0) after 1m0s
[22:03:31.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:119:0)
[22:03:31.415] Timer tick, asking Buildbucket for the build status
[22:03:31.564] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:31.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:120:0) after 1m0s
[22:04:31.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:120:0)
[22:04:31.582] Timer tick, asking Buildbucket for the build status
[22:04:31.625] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:31.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:121:0) after 1m0s
[22:05:31.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:121:0)
[22:05:31.621] Timer tick, asking Buildbucket for the build status
[22:05:31.722] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:31.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:122:0) after 1m0s
[22:06:31.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:122:0)
[22:06:31.703] Timer tick, asking Buildbucket for the build status
[22:06:31.744] Build 8913816926664003040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:31.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079246988710834832:123:0) after 1m0s
[22:06:42.458] Received PubSub notification, asking Buildbucket for the build status
[22:06:42.536] Build 8913816926664003040: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:06:42.536] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557526002078144",
"created_by": "project:fuchsia",
"created_ts": "1557518758101216",
"failure_reason": "BUILD_FAILURE",
"id": "8913816926664003040",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"c0fe5eb39c85756e02d1bf3e05127bc7e55a8973\"}}",
"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-prod-1-us-central1-c-1-d1n6\", \"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\": \"c0fe5eb39c85756e02d1bf3e05127bc7e55a8973\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-1-d1n6\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"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\": [\"4292-d8c05fa\"], \"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": "1557518768051346",
"status": "COMPLETED",
"status_changed_ts": "1557518768520353",
"tags": [
"builder:clang",
"buildset:commit/git/c0fe5eb39c85756e02d1bf3e05127bc7e55a8973",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/c0fe5eb39c85756e02d1bf3e05127bc7e55a8973",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079246988710834832",
"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/8913816926664003040/+/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:44bc2bbe7438c110",
"user_agent:luci-scheduler"
],
"updated_ts": "1557526002195232",
"url": "https://ci.chromium.org/b/8913816926664003040",
"utcnow_ts": "1557526002518962"
}
}
[22:06:42.536] Invocation finished in 2h0m45.771345832s with status FAILED