[21:56:00.353] New invocation is queued and will start shortly
[21:56:01.471] Starting the invocation (attempt 1)
[21:56:01.515] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:56:01.515] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:56:01.515] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9077699915936129248",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"23343c5d90bb6a047506a9977a36d225a934d023\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9077699915936129248",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/23343c5d90bb6a047506a9977a36d225a934d023",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/23343c5d90bb6a047506a9977a36d225a934d023",
"gitiles_ref:refs/heads/master"
]
}
[21:56:02.471] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558994161697355",
"id": "8912269853862427968",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"23343c5d90bb6a047506a9977a36d225a934d023\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558994162196707",
"tags": [
"builder:clang",
"buildset:commit/git/23343c5d90bb6a047506a9977a36d225a934d023",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/23343c5d90bb6a047506a9977a36d225a934d023",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077699915936129248",
"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/8912269853862427968/+/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": "1558994162196808",
"url": "https://ci.chromium.org/b/8912269853862427968",
"utcnow_ts": "1558994162458774"
}
}
[21:56:02.471] Task URL: https://ci.chromium.org/b/8912269853862427968
[21:56:02.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:2:0) after 1m0s
[21:56:41.067] Received PubSub notification, asking Buildbucket for the build status
[21:56:41.121] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:02.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:2:0)
[21:57:02.611] Timer tick, asking Buildbucket for the build status
[21:57:03.627] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:03.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:4:0) after 1m0s
[21:58:03.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:4:0)
[21:58:03.646] Timer tick, asking Buildbucket for the build status
[21:58:03.750] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:03.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:5:0) after 1m0s
[21:59:03.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:5:0)
[21:59:03.819] Timer tick, asking Buildbucket for the build status
[21:59:03.950] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:03.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:6:0) after 1m0s
[22:00:03.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:6:0)
[22:00:03.980] Timer tick, asking Buildbucket for the build status
[22:00:04.128] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:04.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:7:0) after 1m0s
[22:01:04.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:7:0)
[22:01:04.157] Timer tick, asking Buildbucket for the build status
[22:01:08.464] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:08.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:8:0) after 1m0s
[22:02:08.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:8:0)
[22:02:08.481] Timer tick, asking Buildbucket for the build status
[22:02:08.643] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:08.643] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:9:0) after 1m0s
[22:03:08.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:9:0)
[22:03:08.751] Timer tick, asking Buildbucket for the build status
[22:03:08.869] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:08.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:10:0) after 1m0s
[22:04:08.939] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:10:0)
[22:04:08.939] Timer tick, asking Buildbucket for the build status
[22:04:09.025] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:09.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:11:0) after 1m0s
[22:05:09.048] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:11:0)
[22:05:09.048] Timer tick, asking Buildbucket for the build status
[22:05:09.199] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:09.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:12:0) after 1m0s
[22:06:09.170] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:12:0)
[22:06:09.170] Timer tick, asking Buildbucket for the build status
[22:06:09.269] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:09.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:13:0) after 1m0s
[22:07:09.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:13:0)
[22:07:09.319] Timer tick, asking Buildbucket for the build status
[22:07:09.569] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:09.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:14:0) after 1m0s
[22:08:09.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:14:0)
[22:08:09.592] Timer tick, asking Buildbucket for the build status
[22:08:09.929] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:09.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:15:0) after 1m0s
[22:09:09.946] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:15:0)
[22:09:09.946] Timer tick, asking Buildbucket for the build status
[22:09:10.165] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:10.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:16:0) after 1m0s
[22:10:10.367] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:16:0)
[22:10:10.367] Timer tick, asking Buildbucket for the build status
[22:10:10.463] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:10.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:17:0) after 1m0s
[22:11:10.690] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:17:0)
[22:11:10.690] Timer tick, asking Buildbucket for the build status
[22:11:10.752] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:10.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:18:0) after 1m0s
[22:12:10.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:18:0)
[22:12:10.770] Timer tick, asking Buildbucket for the build status
[22:12:10.843] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:10.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:19:0) after 1m0s
[22:13:10.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:19:0)
[22:13:10.910] Timer tick, asking Buildbucket for the build status
[22:13:11.276] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:11.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:20:0) after 1m0s
[22:14:11.350] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:20:0)
[22:14:11.350] Timer tick, asking Buildbucket for the build status
[22:14:11.444] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:11.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:21:0) after 1m0s
[22:15:11.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:21:0)
[22:15:11.465] Timer tick, asking Buildbucket for the build status
[22:15:11.509] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:11.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:22:0) after 1m0s
[22:16:11.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:22:0)
[22:16:11.672] Timer tick, asking Buildbucket for the build status
[22:16:11.758] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:11.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:23:0) after 1m0s
[22:17:11.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:23:0)
[22:17:11.818] Timer tick, asking Buildbucket for the build status
[22:17:11.882] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:11.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:24:0) after 1m0s
[22:18:11.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:24:0)
[22:18:11.899] Timer tick, asking Buildbucket for the build status
[22:18:12.020] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:12.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:25:0) after 1m0s
[22:19:12.041] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:25:0)
[22:19:12.041] Timer tick, asking Buildbucket for the build status
[22:19:12.164] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:12.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:26:0) after 1m0s
[22:20:12.279] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:26:0)
[22:20:12.279] Timer tick, asking Buildbucket for the build status
[22:20:12.367] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:12.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:27:0) after 1m0s
[22:21:12.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:27:0)
[22:21:12.497] Timer tick, asking Buildbucket for the build status
[22:21:12.978] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:12.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:28:0) after 1m0s
[22:22:13.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:28:0)
[22:22:13.044] Timer tick, asking Buildbucket for the build status
[22:22:13.257] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:13.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:29:0) after 1m0s
[22:23:13.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:29:0)
[22:23:13.326] Timer tick, asking Buildbucket for the build status
[22:23:13.568] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:13.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:30:0) after 1m0s
[22:24:13.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:30:0)
[22:24:13.589] Timer tick, asking Buildbucket for the build status
[22:24:13.649] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:13.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:31:0) after 1m0s
[22:25:13.667] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:31:0)
[22:25:13.667] Timer tick, asking Buildbucket for the build status
[22:25:13.743] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:13.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:32:0) after 1m0s
[22:26:13.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:32:0)
[22:26:13.845] Timer tick, asking Buildbucket for the build status
[22:26:14.003] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:14.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:33:0) after 1m0s
[22:27:14.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:33:0)
[22:27:14.086] Timer tick, asking Buildbucket for the build status
[22:27:14.217] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:14.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:34:0) after 1m0s
[22:28:14.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:34:0)
[22:28:14.265] Timer tick, asking Buildbucket for the build status
[22:28:14.361] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:14.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:35:0) after 1m0s
[22:29:14.344] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:35:0)
[22:29:14.344] Timer tick, asking Buildbucket for the build status
[22:29:16.029] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:16.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:36:0) after 1m0s
[22:30:16.052] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:36:0)
[22:30:16.052] Timer tick, asking Buildbucket for the build status
[22:30:16.285] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:16.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:37:0) after 1m0s
[22:31:16.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:37:0)
[22:31:16.384] Timer tick, asking Buildbucket for the build status
[22:31:16.498] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:16.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:38:0) after 1m0s
[22:32:16.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:38:0)
[22:32:16.568] Timer tick, asking Buildbucket for the build status
[22:32:16.677] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:16.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:39:0) after 1m0s
[22:33:16.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:39:0)
[22:33:16.716] Timer tick, asking Buildbucket for the build status
[22:33:16.858] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:16.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:40:0) after 1m0s
[22:34:16.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:40:0)
[22:34:16.880] Timer tick, asking Buildbucket for the build status
[22:34:16.946] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:16.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:41:0) after 1m0s
[22:35:17.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:41:0)
[22:35:17.002] Timer tick, asking Buildbucket for the build status
[22:35:17.082] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:17.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:42:0) after 1m0s
[22:36:17.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:42:0)
[22:36:17.109] Timer tick, asking Buildbucket for the build status
[22:36:17.221] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:17.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:43:0) after 1m0s
[22:37:17.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:43:0)
[22:37:17.261] Timer tick, asking Buildbucket for the build status
[22:37:17.377] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:17.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:44:0) after 1m0s
[22:38:17.349] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:44:0)
[22:38:17.349] Timer tick, asking Buildbucket for the build status
[22:38:17.458] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:17.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:45:0) after 1m0s
[22:39:17.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:45:0)
[22:39:17.479] Timer tick, asking Buildbucket for the build status
[22:39:17.592] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:17.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:46:0) after 1m0s
[22:40:17.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:46:0)
[22:40:17.616] Timer tick, asking Buildbucket for the build status
[22:40:17.794] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:17.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:47:0) after 1m0s
[22:41:17.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:47:0)
[22:41:17.813] Timer tick, asking Buildbucket for the build status
[22:41:18.101] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:18.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:48:0) after 1m0s
[22:42:18.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:48:0)
[22:42:18.173] Timer tick, asking Buildbucket for the build status
[22:42:18.448] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:18.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:49:0) after 1m0s
[22:43:18.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:49:0)
[22:43:18.519] Timer tick, asking Buildbucket for the build status
[22:43:18.582] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:18.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:50:0) after 1m0s
[22:44:18.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:50:0)
[22:44:18.606] Timer tick, asking Buildbucket for the build status
[22:44:18.674] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:18.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:51:0) after 1m0s
[22:45:18.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:51:0)
[22:45:18.800] Timer tick, asking Buildbucket for the build status
[22:45:18.842] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:18.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:52:0) after 1m0s
[22:46:18.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:52:0)
[22:46:18.910] Timer tick, asking Buildbucket for the build status
[22:46:18.976] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:18.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:53:0) after 1m0s
[22:47:19.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:53:0)
[22:47:19.164] Timer tick, asking Buildbucket for the build status
[22:47:19.251] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:19.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:54:0) after 1m0s
[22:48:19.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:54:0)
[22:48:19.412] Timer tick, asking Buildbucket for the build status
[22:48:19.491] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:19.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:55:0) after 1m0s
[22:49:19.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:55:0)
[22:49:19.615] Timer tick, asking Buildbucket for the build status
[22:49:19.960] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:19.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:56:0) after 1m0s
[22:50:20.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:56:0)
[22:50:20.107] Timer tick, asking Buildbucket for the build status
[22:50:20.284] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:20.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:57:0) after 1m0s
[22:51:20.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:57:0)
[22:51:20.308] Timer tick, asking Buildbucket for the build status
[22:51:20.644] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:20.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:58:0) after 1m0s
[22:52:20.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:58:0)
[22:52:20.765] Timer tick, asking Buildbucket for the build status
[22:52:20.838] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:20.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:59:0) after 1m0s
[22:53:20.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:59:0)
[22:53:20.859] Timer tick, asking Buildbucket for the build status
[22:53:21.098] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:21.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:60:0) after 1m0s
[22:54:21.541] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:60:0)
[22:54:21.541] Timer tick, asking Buildbucket for the build status
[22:54:21.587] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:21.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:61:0) after 1m0s
[22:55:21.657] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:61:0)
[22:55:21.657] Timer tick, asking Buildbucket for the build status
[22:55:21.838] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:21.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:62:0) after 1m0s
[22:56:21.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:62:0)
[22:56:21.852] Timer tick, asking Buildbucket for the build status
[22:56:21.946] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:21.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:63:0) after 1m0s
[22:57:21.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:63:0)
[22:57:21.969] Timer tick, asking Buildbucket for the build status
[22:57:22.039] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:22.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:64:0) after 1m0s
[22:58:22.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:64:0)
[22:58:22.061] Timer tick, asking Buildbucket for the build status
[22:58:22.113] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:22.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:65:0) after 1m0s
[22:59:22.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:65:0)
[22:59:22.134] Timer tick, asking Buildbucket for the build status
[22:59:22.294] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:22.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:66:0) after 1m0s
[23:00:22.267] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:66:0)
[23:00:22.267] Timer tick, asking Buildbucket for the build status
[23:00:22.312] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:22.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:67:0) after 1m0s
[23:01:22.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:67:0)
[23:01:22.338] Timer tick, asking Buildbucket for the build status
[23:01:22.550] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:22.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:68:0) after 1m0s
[23:02:22.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:68:0)
[23:02:22.599] Timer tick, asking Buildbucket for the build status
[23:02:22.757] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:22.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:69:0) after 1m0s
[23:03:22.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:69:0)
[23:03:22.815] Timer tick, asking Buildbucket for the build status
[23:03:22.916] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:22.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:70:0) after 1m0s
[23:04:22.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:70:0)
[23:04:22.985] Timer tick, asking Buildbucket for the build status
[23:04:23.037] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:23.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:71:0) after 1m0s
[23:05:23.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:71:0)
[23:05:23.197] Timer tick, asking Buildbucket for the build status
[23:05:23.295] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:23.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:72:0) after 1m0s
[23:06:23.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:72:0)
[23:06:23.326] Timer tick, asking Buildbucket for the build status
[23:06:23.466] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:23.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:73:0) after 1m0s
[23:07:23.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:73:0)
[23:07:23.543] Timer tick, asking Buildbucket for the build status
[23:07:23.661] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:23.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:74:0) after 1m0s
[23:08:23.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:74:0)
[23:08:23.864] Timer tick, asking Buildbucket for the build status
[23:08:23.956] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:23.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:75:0) after 1m0s
[23:09:24.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:75:0)
[23:09:24.135] Timer tick, asking Buildbucket for the build status
[23:09:24.278] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:24.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:76:0) after 1m0s
[23:10:24.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:76:0)
[23:10:24.529] Timer tick, asking Buildbucket for the build status
[23:10:24.639] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:24.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:77:0) after 1m0s
[23:11:24.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:77:0)
[23:11:24.700] Timer tick, asking Buildbucket for the build status
[23:11:24.761] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:24.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:78:0) after 1m0s
[23:12:24.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:78:0)
[23:12:24.836] Timer tick, asking Buildbucket for the build status
[23:12:24.903] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:24.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:79:0) after 1m0s
[23:13:24.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:79:0)
[23:13:24.929] Timer tick, asking Buildbucket for the build status
[23:13:25.082] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:25.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:80:0) after 1m0s
[23:14:25.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:80:0)
[23:14:25.202] Timer tick, asking Buildbucket for the build status
[23:14:25.258] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:25.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:81:0) after 1m0s
[23:15:25.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:81:0)
[23:15:25.271] Timer tick, asking Buildbucket for the build status
[23:15:25.481] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:25.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:82:0) after 1m0s
[23:16:25.528] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:82:0)
[23:16:25.528] Timer tick, asking Buildbucket for the build status
[23:16:25.592] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:25.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:83:0) after 1m0s
[23:17:25.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:83:0)
[23:17:25.596] Timer tick, asking Buildbucket for the build status
[23:17:25.767] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:25.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:84:0) after 1m0s
[23:18:25.834] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:84:0)
[23:18:25.834] Timer tick, asking Buildbucket for the build status
[23:18:25.981] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:25.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:85:0) after 1m0s
[23:19:26.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:85:0)
[23:19:26.006] Timer tick, asking Buildbucket for the build status
[23:19:26.102] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:26.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:86:0) after 1m0s
[23:20:26.141] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:86:0)
[23:20:26.141] Timer tick, asking Buildbucket for the build status
[23:20:27.945] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:27.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:87:0) after 1m0s
[23:21:28.039] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:87:0)
[23:21:28.039] Timer tick, asking Buildbucket for the build status
[23:21:28.156] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:28.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:88:0) after 1m0s
[23:22:28.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:88:0)
[23:22:28.211] Timer tick, asking Buildbucket for the build status
[23:22:28.254] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:28.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:89:0) after 1m0s
[23:23:28.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:89:0)
[23:23:28.377] Timer tick, asking Buildbucket for the build status
[23:23:28.502] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:28.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:90:0) after 1m0s
[23:24:28.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:90:0)
[23:24:28.531] Timer tick, asking Buildbucket for the build status
[23:24:28.588] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:28.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:91:0) after 1m0s
[23:25:28.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:91:0)
[23:25:28.612] Timer tick, asking Buildbucket for the build status
[23:25:28.652] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:28.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:92:0) after 1m0s
[23:26:28.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:92:0)
[23:26:28.697] Timer tick, asking Buildbucket for the build status
[23:26:28.809] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:28.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:93:0) after 1m0s
[23:27:28.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:93:0)
[23:27:28.833] Timer tick, asking Buildbucket for the build status
[23:27:28.903] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:28.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:94:0) after 1m0s
[23:28:28.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:94:0)
[23:28:28.925] Timer tick, asking Buildbucket for the build status
[23:28:28.999] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:28.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:95:0) after 1m0s
[23:29:28.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:95:0)
[23:29:28.994] Timer tick, asking Buildbucket for the build status
[23:29:29.071] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:29.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:96:0) after 1m0s
[23:30:29.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:96:0)
[23:30:29.144] Timer tick, asking Buildbucket for the build status
[23:30:29.217] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:29.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:97:0) after 1m0s
[23:31:29.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:97:0)
[23:31:29.294] Timer tick, asking Buildbucket for the build status
[23:31:29.467] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:29.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:98:0) after 1m0s
[23:32:29.500] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:98:0)
[23:32:29.500] Timer tick, asking Buildbucket for the build status
[23:32:29.541] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:29.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:99:0) after 1m0s
[23:33:29.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:99:0)
[23:33:29.579] Timer tick, asking Buildbucket for the build status
[23:33:29.628] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:29.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:100:0) after 1m0s
[23:34:29.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:100:0)
[23:34:29.648] Timer tick, asking Buildbucket for the build status
[23:34:29.718] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:29.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:101:0) after 1m0s
[23:35:29.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:101:0)
[23:35:29.774] Timer tick, asking Buildbucket for the build status
[23:35:29.857] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:29.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:102:0) after 1m0s
[23:36:29.885] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:102:0)
[23:36:29.885] Timer tick, asking Buildbucket for the build status
[23:36:30.066] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:30.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:103:0) after 1m0s
[23:37:30.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:103:0)
[23:37:30.104] Timer tick, asking Buildbucket for the build status
[23:37:30.149] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:30.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:104:0) after 1m0s
[23:38:30.178] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:104:0)
[23:38:30.178] Timer tick, asking Buildbucket for the build status
[23:38:30.281] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:30.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:105:0) after 1m0s
[23:39:30.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:105:0)
[23:39:30.318] Timer tick, asking Buildbucket for the build status
[23:39:30.375] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:30.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:106:0) after 1m0s
[23:40:30.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:106:0)
[23:40:30.451] Timer tick, asking Buildbucket for the build status
[23:40:30.537] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:30.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:107:0) after 1m0s
[23:41:30.639] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:107:0)
[23:41:30.639] Timer tick, asking Buildbucket for the build status
[23:41:33.361] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:33.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:108:0) after 1m0s
[23:42:33.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:108:0)
[23:42:33.385] Timer tick, asking Buildbucket for the build status
[23:42:33.487] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:33.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:109:0) after 1m0s
[23:43:33.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:109:0)
[23:43:33.534] Timer tick, asking Buildbucket for the build status
[23:43:33.632] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:33.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:110:0) after 1m0s
[23:44:33.681] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:110:0)
[23:44:33.681] Timer tick, asking Buildbucket for the build status
[23:44:33.909] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:33.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:111:0) after 1m0s
[23:45:33.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:111:0)
[23:45:33.930] Timer tick, asking Buildbucket for the build status
[23:45:34.112] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:34.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:112:0) after 1m0s
[23:46:34.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:112:0)
[23:46:34.168] Timer tick, asking Buildbucket for the build status
[23:46:34.226] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:34.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:113:0) after 1m0s
[23:47:34.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:113:0)
[23:47:34.300] Timer tick, asking Buildbucket for the build status
[23:47:34.764] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:34.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:114:0) after 1m0s
[23:48:35.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:114:0)
[23:48:35.036] Timer tick, asking Buildbucket for the build status
[23:48:35.167] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:35.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:115:0) after 1m0s
[23:49:35.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:115:0)
[23:49:35.194] Timer tick, asking Buildbucket for the build status
[23:49:35.283] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:35.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:116:0) after 1m0s
[23:50:35.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:116:0)
[23:50:35.355] Timer tick, asking Buildbucket for the build status
[23:50:35.471] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:35.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:117:0) after 1m0s
[23:51:35.498] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:117:0)
[23:51:35.498] Timer tick, asking Buildbucket for the build status
[23:51:35.810] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:35.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:118:0) after 1m0s
[23:52:35.885] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:118:0)
[23:52:35.885] Timer tick, asking Buildbucket for the build status
[23:52:36.110] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:36.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:119:0) after 1m0s
[23:53:36.170] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:119:0)
[23:53:36.170] Timer tick, asking Buildbucket for the build status
[23:53:36.257] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:36.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:120:0) after 1m0s
[23:54:36.670] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:120:0)
[23:54:36.670] Timer tick, asking Buildbucket for the build status
[23:54:36.726] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:36.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:121:0) after 1m0s
[23:55:36.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:121:0)
[23:55:36.794] Timer tick, asking Buildbucket for the build status
[23:55:36.934] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:36.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:122:0) after 1m0s
[23:56:36.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:122:0)
[23:56:36.975] Timer tick, asking Buildbucket for the build status
[23:56:37.014] Build 8912269853862427968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:37.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077699915936129248:123:0) after 1m0s
[23:57:06.528] Received PubSub notification, asking Buildbucket for the build status
[23:57:08.030] Build 8912269853862427968: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[23:57:08.030] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1559001425927483",
"created_by": "project:fuchsia",
"created_ts": "1558994161697355",
"failure_reason": "INFRA_FAILURE",
"id": "8912269853862427968",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"23343c5d90bb6a047506a9977a36d225a934d023\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"recipe infra failure: Infra Failure: Step('buildbucket.run.collect') (timeout)\"}, \"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-jg6t\", \"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\": \"23343c5d90bb6a047506a9977a36d225a934d023\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-1-jg6t\"], \"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\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"recipe infra failure: Infra Failure: Step('buildbucket.run.collect') (timeout)\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1558994199827198",
"status": "COMPLETED",
"status_changed_ts": "1558994200619099",
"tags": [
"builder:clang",
"buildset:commit/git/23343c5d90bb6a047506a9977a36d225a934d023",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/23343c5d90bb6a047506a9977a36d225a934d023",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077699915936129248",
"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/8912269853862427968/+/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:45141c9d98aec510",
"user_agent:luci-scheduler"
],
"updated_ts": "1559001426187522",
"url": "https://ci.chromium.org/b/8912269853862427968",
"utcnow_ts": "1559001428020087"
}
}
[23:57:08.030] Invocation finished in 2h1m7.693620804s with status FAILED