[11:07:42.827] New invocation is queued and will start shortly
[11:07:43.896] Starting the invocation (attempt 1)
[11:07:43.936] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:07:43.936] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:07:43.937] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078737269577161808",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"472c6ef8b0f53061b049039f9775ab127beafbe4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078737269577161808",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/472c6ef8b0f53061b049039f9775ab127beafbe4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/472c6ef8b0f53061b049039f9775ab127beafbe4",
"gitiles_ref:refs/heads/master"
]
}
[11:07:44.885] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558004864053987",
"id": "8913307207628634224",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"472c6ef8b0f53061b049039f9775ab127beafbe4\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558004864634020",
"tags": [
"builder:clang",
"buildset:commit/git/472c6ef8b0f53061b049039f9775ab127beafbe4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/472c6ef8b0f53061b049039f9775ab127beafbe4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078737269577161808",
"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/8913307207628634224/+/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": "1558004864634141",
"url": "https://ci.chromium.org/b/8913307207628634224",
"utcnow_ts": "1558004864867021"
}
}
[11:07:44.885] Task URL: https://ci.chromium.org/b/8913307207628634224
[11:07:44.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:2:0) after 1m0s
[11:07:58.727] Received PubSub notification, asking Buildbucket for the build status
[11:07:58.804] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:44.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:2:0)
[11:08:44.921] Timer tick, asking Buildbucket for the build status
[11:08:45.043] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:45.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:4:0) after 1m0s
[11:09:45.032] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:4:0)
[11:09:45.032] Timer tick, asking Buildbucket for the build status
[11:09:45.550] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:45.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:5:0) after 1m0s
[11:10:45.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:5:0)
[11:10:45.593] Timer tick, asking Buildbucket for the build status
[11:10:45.678] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:45.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:6:0) after 1m0s
[11:11:45.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:6:0)
[11:11:45.803] Timer tick, asking Buildbucket for the build status
[11:11:46.069] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:46.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:7:0) after 1m0s
[11:12:46.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:7:0)
[11:12:46.077] Timer tick, asking Buildbucket for the build status
[11:12:46.197] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:46.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:8:0) after 1m0s
[11:13:46.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:8:0)
[11:13:46.192] Timer tick, asking Buildbucket for the build status
[11:13:46.314] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:46.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:9:0) after 1m0s
[11:14:46.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:9:0)
[11:14:46.300] Timer tick, asking Buildbucket for the build status
[11:14:46.340] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:46.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:10:0) after 1m0s
[11:15:46.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:10:0)
[11:15:46.362] Timer tick, asking Buildbucket for the build status
[11:15:46.401] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:46.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:11:0) after 1m0s
[11:16:46.424] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:11:0)
[11:16:46.424] Timer tick, asking Buildbucket for the build status
[11:16:46.545] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:46.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:12:0) after 1m0s
[11:17:46.557] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:12:0)
[11:17:46.557] Timer tick, asking Buildbucket for the build status
[11:17:46.670] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:46.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:13:0) after 1m0s
[11:18:46.690] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:13:0)
[11:18:46.690] Timer tick, asking Buildbucket for the build status
[11:18:46.754] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:46.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:14:0) after 1m0s
[11:19:46.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:14:0)
[11:19:46.802] Timer tick, asking Buildbucket for the build status
[11:19:46.857] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:46.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:15:0) after 1m0s
[11:20:46.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:15:0)
[11:20:46.892] Timer tick, asking Buildbucket for the build status
[11:20:47.294] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:47.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:16:0) after 1m0s
[11:21:47.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:16:0)
[11:21:47.323] Timer tick, asking Buildbucket for the build status
[11:21:47.393] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:47.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:17:0) after 1m0s
[11:22:47.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:17:0)
[11:22:47.377] Timer tick, asking Buildbucket for the build status
[11:22:47.475] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:47.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:18:0) after 1m0s
[11:23:47.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:18:0)
[11:23:47.480] Timer tick, asking Buildbucket for the build status
[11:23:47.532] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:47.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:19:0) after 1m0s
[11:24:47.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:19:0)
[11:24:47.580] Timer tick, asking Buildbucket for the build status
[11:24:47.943] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:47.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:20:0) after 1m0s
[11:25:47.961] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:20:0)
[11:25:47.961] Timer tick, asking Buildbucket for the build status
[11:25:48.417] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:48.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:21:0) after 1m0s
[11:26:48.441] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:21:0)
[11:26:48.441] Timer tick, asking Buildbucket for the build status
[11:26:48.578] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:48.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:22:0) after 1m0s
[11:27:48.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:22:0)
[11:27:48.604] Timer tick, asking Buildbucket for the build status
[11:27:48.653] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:48.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:23:0) after 1m0s
[11:28:48.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:23:0)
[11:28:48.672] Timer tick, asking Buildbucket for the build status
[11:28:49.166] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:49.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:24:0) after 1m0s
[11:29:49.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:24:0)
[11:29:49.189] Timer tick, asking Buildbucket for the build status
[11:29:49.252] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:49.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:25:0) after 1m0s
[11:30:49.364] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:25:0)
[11:30:49.364] Timer tick, asking Buildbucket for the build status
[11:30:49.416] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:49.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:26:0) after 1m0s
[11:31:49.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:26:0)
[11:31:49.442] Timer tick, asking Buildbucket for the build status
[11:31:49.507] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:49.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:27:0) after 1m0s
[11:32:49.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:27:0)
[11:32:49.526] Timer tick, asking Buildbucket for the build status
[11:32:49.573] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:49.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:28:0) after 1m0s
[11:33:49.735] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:28:0)
[11:33:49.735] Timer tick, asking Buildbucket for the build status
[11:33:49.820] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:49.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:29:0) after 1m0s
[11:34:49.874] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:29:0)
[11:34:49.874] Timer tick, asking Buildbucket for the build status
[11:34:50.307] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:50.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:30:0) after 1m0s
[11:35:50.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:30:0)
[11:35:50.318] Timer tick, asking Buildbucket for the build status
[11:35:51.818] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:51.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:31:0) after 1m0s
[11:36:51.924] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:31:0)
[11:36:51.924] Timer tick, asking Buildbucket for the build status
[11:36:52.061] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:52.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:32:0) after 1m0s
[11:37:52.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:32:0)
[11:37:52.085] Timer tick, asking Buildbucket for the build status
[11:37:52.129] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:52.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:33:0) after 1m0s
[11:38:52.155] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:33:0)
[11:38:52.155] Timer tick, asking Buildbucket for the build status
[11:38:52.272] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:52.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:34:0) after 1m0s
[11:39:52.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:34:0)
[11:39:52.257] Timer tick, asking Buildbucket for the build status
[11:39:52.348] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:52.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:35:0) after 1m0s
[11:40:52.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:35:0)
[11:40:52.393] Timer tick, asking Buildbucket for the build status
[11:40:52.439] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:52.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:36:0) after 1m0s
[11:41:52.424] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:36:0)
[11:41:52.424] Timer tick, asking Buildbucket for the build status
[11:41:52.715] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:52.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:37:0) after 1m0s
[11:42:52.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:37:0)
[11:42:52.743] Timer tick, asking Buildbucket for the build status
[11:42:52.800] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:52.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:38:0) after 1m0s
[11:43:52.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:38:0)
[11:43:52.833] Timer tick, asking Buildbucket for the build status
[11:43:52.890] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:52.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:39:0) after 1m0s
[11:44:52.891] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:39:0)
[11:44:52.891] Timer tick, asking Buildbucket for the build status
[11:44:52.946] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:52.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:40:0) after 1m0s
[11:45:53.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:40:0)
[11:45:53.006] Timer tick, asking Buildbucket for the build status
[11:45:53.095] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:53.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:41:0) after 1m0s
[11:46:53.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:41:0)
[11:46:53.117] Timer tick, asking Buildbucket for the build status
[11:46:53.197] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:53.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:42:0) after 1m0s
[11:47:53.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:42:0)
[11:47:53.280] Timer tick, asking Buildbucket for the build status
[11:47:53.344] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:53.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:43:0) after 1m0s
[11:48:53.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:43:0)
[11:48:53.343] Timer tick, asking Buildbucket for the build status
[11:48:53.431] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:53.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:44:0) after 1m0s
[11:49:53.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:44:0)
[11:49:53.442] Timer tick, asking Buildbucket for the build status
[11:49:53.566] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:53.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:45:0) after 1m0s
[11:50:53.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:45:0)
[11:50:53.588] Timer tick, asking Buildbucket for the build status
[11:50:53.757] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:53.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:46:0) after 1m0s
[11:51:53.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:46:0)
[11:51:53.779] Timer tick, asking Buildbucket for the build status
[11:51:53.830] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:53.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:47:0) after 1m0s
[11:52:53.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:47:0)
[11:52:53.852] Timer tick, asking Buildbucket for the build status
[11:52:53.965] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:53.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:48:0) after 1m0s
[11:53:53.944] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:48:0)
[11:53:53.944] Timer tick, asking Buildbucket for the build status
[11:53:53.991] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:53.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:49:0) after 1m0s
[11:54:53.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:49:0)
[11:54:53.992] Timer tick, asking Buildbucket for the build status
[11:54:54.040] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:54.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:50:0) after 1m0s
[11:55:54.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:50:0)
[11:55:54.064] Timer tick, asking Buildbucket for the build status
[11:55:54.127] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:54.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:51:0) after 1m0s
[11:56:54.155] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:51:0)
[11:56:54.155] Timer tick, asking Buildbucket for the build status
[11:56:54.223] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:54.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:52:0) after 1m0s
[11:57:54.258] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:52:0)
[11:57:54.259] Timer tick, asking Buildbucket for the build status
[11:57:54.365] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:54.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:53:0) after 1m0s
[11:58:54.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:53:0)
[11:58:54.388] Timer tick, asking Buildbucket for the build status
[11:58:54.486] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:54.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:54:0) after 1m0s
[11:59:54.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:54:0)
[11:59:54.482] Timer tick, asking Buildbucket for the build status
[11:59:54.597] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:54.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:55:0) after 1m0s
[12:00:54.575] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:55:0)
[12:00:54.575] Timer tick, asking Buildbucket for the build status
[12:00:54.810] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:54.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:56:0) after 1m0s
[12:01:54.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:56:0)
[12:01:54.794] Timer tick, asking Buildbucket for the build status
[12:01:54.897] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:54.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:57:0) after 1m0s
[12:02:54.944] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:57:0)
[12:02:54.944] Timer tick, asking Buildbucket for the build status
[12:02:55.152] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:55.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:58:0) after 1m0s
[12:03:55.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:58:0)
[12:03:55.159] Timer tick, asking Buildbucket for the build status
[12:03:55.228] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:55.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:59:0) after 1m0s
[12:04:55.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:59:0)
[12:04:55.259] Timer tick, asking Buildbucket for the build status
[12:04:55.400] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:55.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:60:0) after 1m0s
[12:05:55.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:60:0)
[12:05:55.460] Timer tick, asking Buildbucket for the build status
[12:05:55.621] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:55.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:61:0) after 1m0s
[12:06:55.643] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:61:0)
[12:06:55.643] Timer tick, asking Buildbucket for the build status
[12:06:55.922] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:55.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:62:0) after 1m0s
[12:07:55.954] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:62:0)
[12:07:55.954] Timer tick, asking Buildbucket for the build status
[12:07:56.039] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:56.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:63:0) after 1m0s
[12:08:56.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:63:0)
[12:08:56.065] Timer tick, asking Buildbucket for the build status
[12:08:56.144] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:56.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:64:0) after 1m0s
[12:09:56.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:64:0)
[12:09:56.177] Timer tick, asking Buildbucket for the build status
[12:09:56.276] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:56.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:65:0) after 1m0s
[12:10:56.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:65:0)
[12:10:56.300] Timer tick, asking Buildbucket for the build status
[12:10:56.503] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:56.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:66:0) after 1m0s
[12:11:56.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:66:0)
[12:11:56.477] Timer tick, asking Buildbucket for the build status
[12:11:56.570] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:56.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:67:0) after 1m0s
[12:12:56.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:67:0)
[12:12:56.617] Timer tick, asking Buildbucket for the build status
[12:12:56.707] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:56.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:68:0) after 1m0s
[12:13:57.030] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:68:0)
[12:13:57.030] Timer tick, asking Buildbucket for the build status
[12:13:57.109] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:57.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:69:0) after 1m0s
[12:14:57.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:69:0)
[12:14:57.380] Timer tick, asking Buildbucket for the build status
[12:14:57.439] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:57.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:70:0) after 1m0s
[12:15:57.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:70:0)
[12:15:57.460] Timer tick, asking Buildbucket for the build status
[12:15:57.521] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:57.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:71:0) after 1m0s
[12:16:57.541] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:71:0)
[12:16:57.542] Timer tick, asking Buildbucket for the build status
[12:16:57.618] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:57.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:72:0) after 1m0s
[12:17:57.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:72:0)
[12:17:57.650] Timer tick, asking Buildbucket for the build status
[12:17:58.176] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:58.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:73:0) after 1m0s
[12:18:58.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:73:0)
[12:18:58.198] Timer tick, asking Buildbucket for the build status
[12:18:58.289] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:58.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:74:0) after 1m0s
[12:19:58.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:74:0)
[12:19:58.358] Timer tick, asking Buildbucket for the build status
[12:19:58.426] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:58.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:75:0) after 1m0s
[12:20:58.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:75:0)
[12:20:58.481] Timer tick, asking Buildbucket for the build status
[12:20:58.523] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:58.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:76:0) after 1m0s
[12:21:58.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:76:0)
[12:21:58.560] Timer tick, asking Buildbucket for the build status
[12:21:58.611] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:58.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:77:0) after 1m0s
[12:22:58.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:77:0)
[12:22:58.601] Timer tick, asking Buildbucket for the build status
[12:22:58.645] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:58.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:78:0) after 1m0s
[12:23:58.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:78:0)
[12:23:58.675] Timer tick, asking Buildbucket for the build status
[12:23:58.962] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:58.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:79:0) after 1m0s
[12:24:58.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:79:0)
[12:24:58.965] Timer tick, asking Buildbucket for the build status
[12:24:59.282] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:59.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:80:0) after 1m0s
[12:25:59.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:80:0)
[12:25:59.303] Timer tick, asking Buildbucket for the build status
[12:25:59.341] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:59.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:81:0) after 1m0s
[12:26:59.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:81:0)
[12:26:59.361] Timer tick, asking Buildbucket for the build status
[12:26:59.403] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:59.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:82:0) after 1m0s
[12:27:59.425] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:82:0)
[12:27:59.425] Timer tick, asking Buildbucket for the build status
[12:27:59.563] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:59.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:83:0) after 1m0s
[12:28:59.585] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:83:0)
[12:28:59.585] Timer tick, asking Buildbucket for the build status
[12:28:59.664] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:59.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:84:0) after 1m0s
[12:29:59.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:84:0)
[12:29:59.730] Timer tick, asking Buildbucket for the build status
[12:29:59.775] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:59.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:85:0) after 1m0s
[12:30:59.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:85:0)
[12:30:59.751] Timer tick, asking Buildbucket for the build status
[12:30:59.993] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:59.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:86:0) after 1m0s
[12:32:00.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:86:0)
[12:32:00.698] Timer tick, asking Buildbucket for the build status
[12:32:00.763] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:00.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:87:0) after 1m0s
[12:33:00.811] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:87:0)
[12:33:00.811] Timer tick, asking Buildbucket for the build status
[12:33:00.879] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:00.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:88:0) after 1m0s
[12:34:00.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:88:0)
[12:34:00.899] Timer tick, asking Buildbucket for the build status
[12:34:00.952] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:00.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:89:0) after 1m0s
[12:35:00.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:89:0)
[12:35:00.945] Timer tick, asking Buildbucket for the build status
[12:35:01.054] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:01.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:90:0) after 1m0s
[12:36:01.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:90:0)
[12:36:01.114] Timer tick, asking Buildbucket for the build status
[12:36:01.204] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:01.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:91:0) after 1m0s
[12:37:01.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:91:0)
[12:37:01.247] Timer tick, asking Buildbucket for the build status
[12:37:01.405] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:01.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:92:0) after 1m0s
[12:38:01.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:92:0)
[12:38:01.433] Timer tick, asking Buildbucket for the build status
[12:38:01.557] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:01.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:93:0) after 1m0s
[12:39:01.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:93:0)
[12:39:01.588] Timer tick, asking Buildbucket for the build status
[12:39:01.693] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:01.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:94:0) after 1m0s
[12:40:01.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:94:0)
[12:40:01.707] Timer tick, asking Buildbucket for the build status
[12:40:02.144] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:02.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:95:0) after 1m0s
[12:41:02.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:95:0)
[12:41:02.157] Timer tick, asking Buildbucket for the build status
[12:41:02.541] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:02.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:96:0) after 1m0s
[12:42:02.566] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:96:0)
[12:42:02.566] Timer tick, asking Buildbucket for the build status
[12:42:02.691] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:02.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:97:0) after 1m0s
[12:43:02.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:97:0)
[12:43:02.720] Timer tick, asking Buildbucket for the build status
[12:43:02.844] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:02.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:98:0) after 1m0s
[12:44:02.890] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:98:0)
[12:44:02.890] Timer tick, asking Buildbucket for the build status
[12:44:03.003] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:03.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:99:0) after 1m0s
[12:45:03.043] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:99:0)
[12:45:03.043] Timer tick, asking Buildbucket for the build status
[12:45:03.206] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:03.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:100:0) after 1m0s
[12:46:03.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:100:0)
[12:46:03.429] Timer tick, asking Buildbucket for the build status
[12:46:03.527] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:03.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:101:0) after 1m0s
[12:47:03.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:101:0)
[12:47:03.617] Timer tick, asking Buildbucket for the build status
[12:47:03.690] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:03.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:102:0) after 1m0s
[12:48:03.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:102:0)
[12:48:03.820] Timer tick, asking Buildbucket for the build status
[12:48:04.230] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:04.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:103:0) after 1m0s
[12:49:04.263] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:103:0)
[12:49:04.263] Timer tick, asking Buildbucket for the build status
[12:49:04.485] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:04.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:104:0) after 1m0s
[12:50:04.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:104:0)
[12:50:04.499] Timer tick, asking Buildbucket for the build status
[12:50:04.588] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:04.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:105:0) after 1m0s
[12:51:04.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:105:0)
[12:51:04.611] Timer tick, asking Buildbucket for the build status
[12:51:04.980] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:04.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:106:0) after 1m0s
[12:52:05.011] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:106:0)
[12:52:05.011] Timer tick, asking Buildbucket for the build status
[12:52:05.550] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:05.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:107:0) after 1m0s
[12:53:05.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:107:0)
[12:53:05.607] Timer tick, asking Buildbucket for the build status
[12:53:05.687] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:05.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:108:0) after 1m0s
[12:54:05.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:108:0)
[12:54:05.731] Timer tick, asking Buildbucket for the build status
[12:54:05.814] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:05.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:109:0) after 1m0s
[12:55:05.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:109:0)
[12:55:05.789] Timer tick, asking Buildbucket for the build status
[12:55:06.493] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:06.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:110:0) after 1m0s
[12:56:06.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:110:0)
[12:56:06.517] Timer tick, asking Buildbucket for the build status
[12:56:06.579] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:06.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:111:0) after 1m0s
[12:57:06.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:111:0)
[12:57:06.607] Timer tick, asking Buildbucket for the build status
[12:57:08.440] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:08.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:112:0) after 1m0s
[12:58:08.425] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:112:0)
[12:58:08.425] Timer tick, asking Buildbucket for the build status
[12:58:08.476] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:08.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:113:0) after 1m0s
[12:59:08.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:113:0)
[12:59:08.517] Timer tick, asking Buildbucket for the build status
[12:59:08.599] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:08.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:114:0) after 1m0s
[13:00:08.632] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:114:0)
[13:00:08.632] Timer tick, asking Buildbucket for the build status
[13:00:08.979] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:08.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:115:0) after 1m0s
[13:01:09.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:115:0)
[13:01:09.016] Timer tick, asking Buildbucket for the build status
[13:01:09.154] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:09.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:116:0) after 1m0s
[13:02:09.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:116:0)
[13:02:09.180] Timer tick, asking Buildbucket for the build status
[13:02:09.254] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:09.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:117:0) after 1m0s
[13:03:09.272] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:117:0)
[13:03:09.272] Timer tick, asking Buildbucket for the build status
[13:03:09.351] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:09.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:118:0) after 1m0s
[13:04:09.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:118:0)
[13:04:09.387] Timer tick, asking Buildbucket for the build status
[13:04:09.508] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:09.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:119:0) after 1m0s
[13:05:09.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:119:0)
[13:05:09.540] Timer tick, asking Buildbucket for the build status
[13:05:09.676] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:09.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:120:0) after 1m0s
[13:06:09.763] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:120:0)
[13:06:09.763] Timer tick, asking Buildbucket for the build status
[13:06:09.845] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:09.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:121:0) after 1m0s
[13:07:09.846] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:121:0)
[13:07:09.846] Timer tick, asking Buildbucket for the build status
[13:07:09.991] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:09.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:122:0) after 1m0s
[13:08:10.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:122:0)
[13:08:10.063] Timer tick, asking Buildbucket for the build status
[13:08:10.120] Build 8913307207628634224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:10.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078737269577161808:123:0) after 1m0s
[13:08:21.233] Received PubSub notification, asking Buildbucket for the build status
[13:08:21.343] Build 8913307207628634224: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:08:21.343] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558012099237053",
"created_by": "project:fuchsia",
"created_ts": "1558004864053987",
"failure_reason": "BUILD_FAILURE",
"id": "8913307207628634224",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"472c6ef8b0f53061b049039f9775ab127beafbe4\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-b-0-n5ls\", \"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\": \"472c6ef8b0f53061b049039f9775ab127beafbe4\", \"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-n5ls\"], \"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\": [\"4300-ce42b68\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1558004877574921",
"status": "COMPLETED",
"status_changed_ts": "1558004877948676",
"tags": [
"builder:clang",
"buildset:commit/git/472c6ef8b0f53061b049039f9775ab127beafbe4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/472c6ef8b0f53061b049039f9775ab127beafbe4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078737269577161808",
"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/8913307207628634224/+/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:44d92521148abc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558012100658153",
"url": "https://ci.chromium.org/b/8913307207628634224",
"utcnow_ts": "1558012101334008"
}
}
[13:08:21.343] Invocation finished in 2h0m38.531589105s with status FAILED