[09:58:13.703] New invocation is queued and will start shortly
[09:58:15.020] Starting the invocation (attempt 1)
[09:58:15.049] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:58:15.049] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:58:15.049] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9077835671556865248",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"603ca511f9169df81e6332721a0f37ab30160df7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9077835671556865248",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/603ca511f9169df81e6332721a0f37ab30160df7",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/603ca511f9169df81e6332721a0f37ab30160df7",
"gitiles_ref:refs/heads/master"
]
}
[09:58:16.151] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558864695177944",
"id": "8912405609348681056",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"603ca511f9169df81e6332721a0f37ab30160df7\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558864695696161",
"tags": [
"builder:clang",
"buildset:commit/git/603ca511f9169df81e6332721a0f37ab30160df7",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/603ca511f9169df81e6332721a0f37ab30160df7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077835671556865248",
"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/8912405609348681056/+/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": "1558864695697628",
"url": "https://ci.chromium.org/b/8912405609348681056",
"utcnow_ts": "1558864695924045"
}
}
[09:58:16.151] Task URL: https://ci.chromium.org/b/8912405609348681056
[09:58:16.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:2:0) after 1m0s
[09:58:37.817] Received PubSub notification, asking Buildbucket for the build status
[09:58:37.907] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:16.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:2:0)
[09:59:16.280] Timer tick, asking Buildbucket for the build status
[09:59:16.370] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:16.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:4:0) after 1m0s
[10:00:16.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:4:0)
[10:00:16.515] Timer tick, asking Buildbucket for the build status
[10:00:16.727] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:16.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:5:0) after 1m0s
[10:01:16.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:5:0)
[10:01:16.745] Timer tick, asking Buildbucket for the build status
[10:01:16.785] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:16.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:6:0) after 1m0s
[10:02:16.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:6:0)
[10:02:16.799] Timer tick, asking Buildbucket for the build status
[10:02:16.912] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:16.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:7:0) after 1m0s
[10:03:16.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:7:0)
[10:03:16.942] Timer tick, asking Buildbucket for the build status
[10:03:17.108] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:17.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:8:0) after 1m0s
[10:04:17.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:8:0)
[10:04:17.128] Timer tick, asking Buildbucket for the build status
[10:04:17.451] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:17.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:9:0) after 1m0s
[10:05:17.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:9:0)
[10:05:17.486] Timer tick, asking Buildbucket for the build status
[10:05:17.683] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:17.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:10:0) after 1m0s
[10:06:17.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:10:0)
[10:06:17.769] Timer tick, asking Buildbucket for the build status
[10:06:17.846] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:17.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:11:0) after 1m0s
[10:07:17.863] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:11:0)
[10:07:17.863] Timer tick, asking Buildbucket for the build status
[10:07:18.679] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:18.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:12:0) after 1m0s
[10:08:18.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:12:0)
[10:08:18.720] Timer tick, asking Buildbucket for the build status
[10:08:18.793] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:18.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:13:0) after 1m0s
[10:09:18.816] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:13:0)
[10:09:18.816] Timer tick, asking Buildbucket for the build status
[10:09:18.864] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:18.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:14:0) after 1m0s
[10:10:18.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:14:0)
[10:10:18.882] Timer tick, asking Buildbucket for the build status
[10:10:19.192] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:19.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:15:0) after 1m0s
[10:11:19.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:15:0)
[10:11:19.215] Timer tick, asking Buildbucket for the build status
[10:11:19.499] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:19.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:16:0) after 1m0s
[10:12:19.631] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:16:0)
[10:12:19.631] Timer tick, asking Buildbucket for the build status
[10:12:19.781] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:19.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:17:0) after 1m0s
[10:13:19.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:17:0)
[10:13:19.917] Timer tick, asking Buildbucket for the build status
[10:13:20.018] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:20.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:18:0) after 1m0s
[10:14:20.267] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:18:0)
[10:14:20.267] Timer tick, asking Buildbucket for the build status
[10:14:20.484] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:20.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:19:0) after 1m0s
[10:15:20.639] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:19:0)
[10:15:20.639] Timer tick, asking Buildbucket for the build status
[10:15:20.866] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:20.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:20:0) after 1m0s
[10:16:20.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:20:0)
[10:16:20.902] Timer tick, asking Buildbucket for the build status
[10:16:21.025] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:21.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:21:0) after 1m0s
[10:17:21.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:21:0)
[10:17:21.156] Timer tick, asking Buildbucket for the build status
[10:17:21.237] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:21.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:22:0) after 1m0s
[10:18:21.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:22:0)
[10:18:21.446] Timer tick, asking Buildbucket for the build status
[10:18:21.518] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:21.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:23:0) after 1m0s
[10:19:21.573] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:23:0)
[10:19:21.573] Timer tick, asking Buildbucket for the build status
[10:19:21.638] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:21.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:24:0) after 1m0s
[10:20:22.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:24:0)
[10:20:22.556] Timer tick, asking Buildbucket for the build status
[10:20:22.721] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:22.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:25:0) after 1m0s
[10:21:23.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:25:0)
[10:21:23.109] Timer tick, asking Buildbucket for the build status
[10:21:23.887] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:23.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:26:0) after 1m0s
[10:22:24.234] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:26:0)
[10:22:24.234] Timer tick, asking Buildbucket for the build status
[10:22:24.432] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:24.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:27:0) after 1m0s
[10:23:24.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:27:0)
[10:23:24.581] Timer tick, asking Buildbucket for the build status
[10:23:25.635] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:25.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:28:0) after 1m0s
[10:24:25.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:28:0)
[10:24:25.611] Timer tick, asking Buildbucket for the build status
[10:24:26.149] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:26.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:29:0) after 1m0s
[10:25:26.788] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:29:0)
[10:25:26.788] Timer tick, asking Buildbucket for the build status
[10:25:27.094] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:27.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:30:0) after 1m0s
[10:26:37.040] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:30:0)
[10:26:37.040] Timer tick, asking Buildbucket for the build status
[10:26:37.148] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:37.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:31:0) after 1m0s
[10:27:37.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:31:0)
[10:27:37.209] Timer tick, asking Buildbucket for the build status
[10:27:37.279] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:37.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:32:0) after 1m0s
[10:28:37.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:32:0)
[10:28:37.318] Timer tick, asking Buildbucket for the build status
[10:28:37.408] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:37.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:33:0) after 1m0s
[10:29:37.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:33:0)
[10:29:37.436] Timer tick, asking Buildbucket for the build status
[10:29:37.481] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:37.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:34:0) after 1m0s
[10:30:37.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:34:0)
[10:30:37.503] Timer tick, asking Buildbucket for the build status
[10:30:37.585] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:37.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:35:0) after 1m0s
[10:31:37.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:35:0)
[10:31:37.607] Timer tick, asking Buildbucket for the build status
[10:31:37.888] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:37.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:36:0) after 1m0s
[10:32:37.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:36:0)
[10:32:37.912] Timer tick, asking Buildbucket for the build status
[10:32:37.983] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:37.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:37:0) after 1m0s
[10:33:38.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:37:0)
[10:33:38.004] Timer tick, asking Buildbucket for the build status
[10:33:38.093] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:38.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:38:0) after 1m0s
[10:34:38.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:38:0)
[10:34:38.124] Timer tick, asking Buildbucket for the build status
[10:34:38.186] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:38.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:39:0) after 1m0s
[10:35:38.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:39:0)
[10:35:38.264] Timer tick, asking Buildbucket for the build status
[10:35:38.562] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:38.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:40:0) after 1m0s
[10:36:38.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:40:0)
[10:36:38.614] Timer tick, asking Buildbucket for the build status
[10:36:38.811] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:38.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:41:0) after 1m0s
[10:37:38.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:41:0)
[10:37:38.867] Timer tick, asking Buildbucket for the build status
[10:37:38.911] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:38.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:42:0) after 1m0s
[10:38:38.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:42:0)
[10:38:38.934] Timer tick, asking Buildbucket for the build status
[10:38:39.022] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:39.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:43:0) after 1m0s
[10:39:39.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:43:0)
[10:39:39.085] Timer tick, asking Buildbucket for the build status
[10:39:39.200] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:39.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:44:0) after 1m0s
[10:40:39.320] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:44:0)
[10:40:39.320] Timer tick, asking Buildbucket for the build status
[10:40:39.369] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:39.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:45:0) after 1m0s
[10:41:39.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:45:0)
[10:41:39.389] Timer tick, asking Buildbucket for the build status
[10:41:39.435] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:39.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:46:0) after 1m0s
[10:42:39.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:46:0)
[10:42:39.468] Timer tick, asking Buildbucket for the build status
[10:42:39.514] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:39.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:47:0) after 1m0s
[10:43:39.555] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:47:0)
[10:43:39.555] Timer tick, asking Buildbucket for the build status
[10:43:39.686] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:39.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:48:0) after 1m0s
[10:44:39.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:48:0)
[10:44:39.706] Timer tick, asking Buildbucket for the build status
[10:44:39.793] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:39.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:49:0) after 1m0s
[10:45:39.814] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:49:0)
[10:45:39.814] Timer tick, asking Buildbucket for the build status
[10:45:40.506] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:40.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:50:0) after 1m0s
[10:46:40.532] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:50:0)
[10:46:40.532] Timer tick, asking Buildbucket for the build status
[10:46:40.586] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:40.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:51:0) after 1m0s
[10:47:40.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:51:0)
[10:47:40.607] Timer tick, asking Buildbucket for the build status
[10:47:40.931] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:40.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:52:0) after 1m0s
[10:48:41.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:52:0)
[10:48:41.105] Timer tick, asking Buildbucket for the build status
[10:48:41.236] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:41.236] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:53:0) after 1m0s
[10:49:41.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:53:0)
[10:49:41.451] Timer tick, asking Buildbucket for the build status
[10:49:41.541] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:41.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:54:0) after 1m0s
[10:50:41.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:54:0)
[10:50:41.560] Timer tick, asking Buildbucket for the build status
[10:50:41.688] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:41.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:55:0) after 1m0s
[10:51:41.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:55:0)
[10:51:41.798] Timer tick, asking Buildbucket for the build status
[10:51:41.845] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:41.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:56:0) after 1m0s
[10:52:41.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:56:0)
[10:52:41.866] Timer tick, asking Buildbucket for the build status
[10:52:42.143] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:42.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:57:0) after 1m0s
[10:53:44.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:57:0)
[10:53:44.281] Timer tick, asking Buildbucket for the build status
[10:53:44.384] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:44.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:58:0) after 1m0s
[10:54:44.545] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:58:0)
[10:54:44.545] Timer tick, asking Buildbucket for the build status
[10:54:44.614] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:44.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:59:0) after 1m0s
[10:55:44.635] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:59:0)
[10:55:44.635] Timer tick, asking Buildbucket for the build status
[10:55:45.693] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:45.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:60:0) after 1m0s
[10:56:46.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:60:0)
[10:56:46.079] Timer tick, asking Buildbucket for the build status
[10:56:46.182] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:46.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:61:0) after 1m0s
[10:57:46.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:61:0)
[10:57:46.210] Timer tick, asking Buildbucket for the build status
[10:57:46.270] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:46.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:62:0) after 1m0s
[10:58:46.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:62:0)
[10:58:46.403] Timer tick, asking Buildbucket for the build status
[10:58:46.621] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:46.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:63:0) after 1m0s
[10:59:46.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:63:0)
[10:59:46.774] Timer tick, asking Buildbucket for the build status
[10:59:46.898] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:46.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:64:0) after 1m0s
[11:00:46.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:64:0)
[11:00:46.923] Timer tick, asking Buildbucket for the build status
[11:00:47.001] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:47.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:65:0) after 1m0s
[11:01:47.032] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:65:0)
[11:01:47.032] Timer tick, asking Buildbucket for the build status
[11:01:47.593] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:47.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:66:0) after 1m0s
[11:02:47.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:66:0)
[11:02:47.640] Timer tick, asking Buildbucket for the build status
[11:02:47.714] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:47.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:67:0) after 1m0s
[11:03:47.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:67:0)
[11:03:47.718] Timer tick, asking Buildbucket for the build status
[11:03:47.786] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:47.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:68:0) after 1m0s
[11:04:47.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:68:0)
[11:04:47.864] Timer tick, asking Buildbucket for the build status
[11:04:48.037] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:48.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:69:0) after 1m0s
[11:05:48.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:69:0)
[11:05:48.065] Timer tick, asking Buildbucket for the build status
[11:05:48.137] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:48.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:70:0) after 1m0s
[11:06:48.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:70:0)
[11:06:48.371] Timer tick, asking Buildbucket for the build status
[11:06:48.452] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:48.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:71:0) after 1m0s
[11:07:48.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:71:0)
[11:07:48.837] Timer tick, asking Buildbucket for the build status
[11:07:48.968] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:48.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:72:0) after 1m0s
[11:08:49.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:72:0)
[11:08:49.208] Timer tick, asking Buildbucket for the build status
[11:08:49.276] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:49.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:73:0) after 1m0s
[11:09:49.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:73:0)
[11:09:49.346] Timer tick, asking Buildbucket for the build status
[11:09:49.420] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:49.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:74:0) after 1m0s
[11:10:49.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:74:0)
[11:10:49.553] Timer tick, asking Buildbucket for the build status
[11:10:49.736] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:49.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:75:0) after 1m0s
[11:11:49.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:75:0)
[11:11:49.811] Timer tick, asking Buildbucket for the build status
[11:11:49.849] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:49.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:76:0) after 1m0s
[11:12:49.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:76:0)
[11:12:49.869] Timer tick, asking Buildbucket for the build status
[11:12:50.337] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:50.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:77:0) after 1m0s
[11:13:50.376] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:77:0)
[11:13:50.376] Timer tick, asking Buildbucket for the build status
[11:13:50.476] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:50.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:78:0) after 1m0s
[11:14:50.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:78:0)
[11:14:50.591] Timer tick, asking Buildbucket for the build status
[11:14:50.673] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:50.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:79:0) after 1m0s
[11:15:50.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:79:0)
[11:15:50.853] Timer tick, asking Buildbucket for the build status
[11:15:50.984] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:50.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:80:0) after 1m0s
[11:16:51.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:80:0)
[11:16:51.057] Timer tick, asking Buildbucket for the build status
[11:16:51.110] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:51.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:81:0) after 1m0s
[11:17:51.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:81:0)
[11:17:51.390] Timer tick, asking Buildbucket for the build status
[11:17:51.508] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:51.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:82:0) after 1m0s
[11:18:51.528] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:82:0)
[11:18:51.528] Timer tick, asking Buildbucket for the build status
[11:18:51.576] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:51.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:83:0) after 1m0s
[11:19:51.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:83:0)
[11:19:51.700] Timer tick, asking Buildbucket for the build status
[11:19:51.771] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:51.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:84:0) after 1m0s
[11:20:51.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:84:0)
[11:20:51.793] Timer tick, asking Buildbucket for the build status
[11:20:51.855] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:51.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:85:0) after 1m0s
[11:21:52.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:85:0)
[11:21:52.330] Timer tick, asking Buildbucket for the build status
[11:21:52.408] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:52.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:86:0) after 1m0s
[11:22:52.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:86:0)
[11:22:52.428] Timer tick, asking Buildbucket for the build status
[11:22:52.491] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:52.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:87:0) after 1m0s
[11:23:52.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:87:0)
[11:23:52.560] Timer tick, asking Buildbucket for the build status
[11:23:52.697] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:52.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:88:0) after 1m0s
[11:24:52.825] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:88:0)
[11:24:52.825] Timer tick, asking Buildbucket for the build status
[11:24:56.117] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:56.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:89:0) after 1m0s
[11:25:56.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:89:0)
[11:25:56.206] Timer tick, asking Buildbucket for the build status
[11:25:56.275] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:56.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:90:0) after 1m0s
[11:26:56.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:90:0)
[11:26:56.431] Timer tick, asking Buildbucket for the build status
[11:26:56.637] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:56.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:91:0) after 1m0s
[11:27:56.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:91:0)
[11:27:56.630] Timer tick, asking Buildbucket for the build status
[11:27:56.719] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:56.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:92:0) after 1m0s
[11:28:56.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:92:0)
[11:28:56.881] Timer tick, asking Buildbucket for the build status
[11:28:57.117] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:57.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:93:0) after 1m0s
[11:29:57.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:93:0)
[11:29:57.136] Timer tick, asking Buildbucket for the build status
[11:29:57.658] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:57.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:94:0) after 1m0s
[11:30:57.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:94:0)
[11:30:57.818] Timer tick, asking Buildbucket for the build status
[11:30:57.871] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:57.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:95:0) after 1m0s
[11:31:57.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:95:0)
[11:31:57.929] Timer tick, asking Buildbucket for the build status
[11:31:57.993] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:57.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:96:0) after 1m0s
[11:32:58.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:96:0)
[11:32:58.017] Timer tick, asking Buildbucket for the build status
[11:32:58.108] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:58.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:97:0) after 1m0s
[11:33:58.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:97:0)
[11:33:58.131] Timer tick, asking Buildbucket for the build status
[11:33:58.195] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:58.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:98:0) after 1m0s
[11:34:58.285] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:98:0)
[11:34:58.285] Timer tick, asking Buildbucket for the build status
[11:34:58.340] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:58.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:99:0) after 1m0s
[11:35:58.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:99:0)
[11:35:58.484] Timer tick, asking Buildbucket for the build status
[11:35:58.671] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:58.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:100:0) after 1m0s
[11:36:58.733] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:100:0)
[11:36:58.733] Timer tick, asking Buildbucket for the build status
[11:36:58.838] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:58.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:101:0) after 1m0s
[11:37:58.856] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:101:0)
[11:37:58.856] Timer tick, asking Buildbucket for the build status
[11:37:58.911] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:58.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:102:0) after 1m0s
[11:38:59.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:102:0)
[11:38:59.096] Timer tick, asking Buildbucket for the build status
[11:38:59.199] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:59.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:103:0) after 1m0s
[11:39:59.235] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:103:0)
[11:39:59.235] Timer tick, asking Buildbucket for the build status
[11:39:59.542] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:59.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:104:0) after 1m0s
[11:40:59.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:104:0)
[11:40:59.563] Timer tick, asking Buildbucket for the build status
[11:40:59.624] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:59.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:105:0) after 1m0s
[11:41:59.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:105:0)
[11:41:59.666] Timer tick, asking Buildbucket for the build status
[11:41:59.726] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:59.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:106:0) after 1m0s
[11:42:59.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:106:0)
[11:42:59.867] Timer tick, asking Buildbucket for the build status
[11:42:59.969] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:59.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:107:0) after 1m0s
[11:43:59.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:107:0)
[11:43:59.993] Timer tick, asking Buildbucket for the build status
[11:44:00.066] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:00.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:108:0) after 1m0s
[11:45:00.142] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:108:0)
[11:45:00.142] Timer tick, asking Buildbucket for the build status
[11:45:00.219] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:00.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:109:0) after 1m0s
[11:46:00.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:109:0)
[11:46:00.245] Timer tick, asking Buildbucket for the build status
[11:46:00.301] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:00.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:110:0) after 1m0s
[11:47:00.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:110:0)
[11:47:00.294] Timer tick, asking Buildbucket for the build status
[11:47:00.362] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:00.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:111:0) after 1m0s
[11:48:00.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:111:0)
[11:48:00.483] Timer tick, asking Buildbucket for the build status
[11:48:00.538] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:00.538] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:112:0) after 1m0s
[11:49:00.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:112:0)
[11:49:00.559] Timer tick, asking Buildbucket for the build status
[11:49:00.642] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:00.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:113:0) after 1m0s
[11:50:00.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:113:0)
[11:50:00.687] Timer tick, asking Buildbucket for the build status
[11:50:00.756] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:00.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:114:0) after 1m0s
[11:51:00.750] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:114:0)
[11:51:00.750] Timer tick, asking Buildbucket for the build status
[11:51:00.982] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:00.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:115:0) after 1m0s
[11:52:01.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:115:0)
[11:52:01.106] Timer tick, asking Buildbucket for the build status
[11:52:01.516] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:01.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:116:0) after 1m0s
[11:53:01.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:116:0)
[11:53:01.537] Timer tick, asking Buildbucket for the build status
[11:53:01.734] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:01.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:117:0) after 1m0s
[11:54:01.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:117:0)
[11:54:01.756] Timer tick, asking Buildbucket for the build status
[11:54:02.112] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:02.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:118:0) after 1m0s
[11:55:02.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:118:0)
[11:55:02.164] Timer tick, asking Buildbucket for the build status
[11:55:02.342] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:02.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:119:0) after 1m0s
[11:56:02.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:119:0)
[11:56:02.371] Timer tick, asking Buildbucket for the build status
[11:56:03.755] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:03.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:120:0) after 1m0s
[11:57:03.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:120:0)
[11:57:03.778] Timer tick, asking Buildbucket for the build status
[11:57:03.995] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:03.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:121:0) after 1m0s
[11:58:04.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:121:0)
[11:58:04.019] Timer tick, asking Buildbucket for the build status
[11:58:04.132] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:04.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:122:0) after 1m0s
[11:59:04.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:122:0)
[11:59:04.159] Timer tick, asking Buildbucket for the build status
[11:59:04.285] Build 8912405609348681056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:04.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077835671556865248:123:0) after 1m0s
[11:59:07.712] Received PubSub notification, asking Buildbucket for the build status
[11:59:07.842] Build 8912405609348681056: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[11:59:07.842] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558871945513691",
"created_by": "project:fuchsia",
"created_ts": "1558864695177944",
"failure_reason": "INFRA_FAILURE",
"id": "8912405609348681056",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"603ca511f9169df81e6332721a0f37ab30160df7\"}}",
"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-b-0-1m3r\", \"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\": \"603ca511f9169df81e6332721a0f37ab30160df7\", \"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-b-0-1m3r\"], \"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-b\"]}}, \"ui\": {\"info\": \"recipe infra failure: Infra Failure: Step('buildbucket.run.collect') (timeout)\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1558864714994911",
"status": "COMPLETED",
"status_changed_ts": "1558864716006444",
"tags": [
"builder:clang",
"buildset:commit/git/603ca511f9169df81e6332721a0f37ab30160df7",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/603ca511f9169df81e6332721a0f37ab30160df7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077835671556865248",
"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/8912405609348681056/+/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:450c651bcf810b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558871947005226",
"url": "https://ci.chromium.org/b/8912405609348681056",
"utcnow_ts": "1558871947833400"
}
}
[11:59:07.842] Invocation finished in 2h0m54.155491762s with status FAILED