[19:42:33.150] New invocation is queued and will start shortly
[19:42:34.503] Starting the invocation (attempt 1)
[19:42:34.543] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:42:34.543] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:42:34.543] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078070699970353104",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"cb64cd9b6041c2c8aaca6d01023e5d7963873701\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078070699970353104",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/cb64cd9b6041c2c8aaca6d01023e5d7963873701",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/cb64cd9b6041c2c8aaca6d01023e5d7963873701",
"gitiles_ref:refs/heads/master"
]
}
[19:42:36.411] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558640554667401",
"id": "8912640637707943008",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"cb64cd9b6041c2c8aaca6d01023e5d7963873701\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558640555179675",
"tags": [
"builder:clang",
"buildset:commit/git/cb64cd9b6041c2c8aaca6d01023e5d7963873701",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/cb64cd9b6041c2c8aaca6d01023e5d7963873701",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078070699970353104",
"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/8912640637707943008/+/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": "1558640555179764",
"url": "https://ci.chromium.org/b/8912640637707943008",
"utcnow_ts": "1558640556404972"
}
}
[19:42:36.411] Task URL: https://ci.chromium.org/b/8912640637707943008
[19:42:36.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:2:0) after 1m0s
[19:42:54.656] Received PubSub notification, asking Buildbucket for the build status
[19:42:54.709] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:36.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:2:0)
[19:43:36.407] Timer tick, asking Buildbucket for the build status
[19:43:36.861] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:36.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:4:0) after 1m0s
[19:44:36.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:4:0)
[19:44:36.936] Timer tick, asking Buildbucket for the build status
[19:44:37.042] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:37.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:5:0) after 1m0s
[19:45:37.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:5:0)
[19:45:37.097] Timer tick, asking Buildbucket for the build status
[19:45:37.254] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:37.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:6:0) after 1m0s
[19:46:37.310] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:6:0)
[19:46:37.310] Timer tick, asking Buildbucket for the build status
[19:46:38.056] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:38.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:7:0) after 1m0s
[19:47:38.125] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:7:0)
[19:47:38.125] Timer tick, asking Buildbucket for the build status
[19:47:38.216] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:38.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:8:0) after 1m0s
[19:48:38.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:8:0)
[19:48:38.294] Timer tick, asking Buildbucket for the build status
[19:48:38.571] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:38.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:9:0) after 1m0s
[19:49:38.634] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:9:0)
[19:49:38.634] Timer tick, asking Buildbucket for the build status
[19:49:38.705] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:38.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:10:0) after 1m0s
[19:50:38.763] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:10:0)
[19:50:38.763] Timer tick, asking Buildbucket for the build status
[19:50:38.896] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:38.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:11:0) after 1m0s
[19:51:38.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:11:0)
[19:51:38.951] Timer tick, asking Buildbucket for the build status
[19:51:39.227] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:39.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:12:0) after 1m0s
[19:52:39.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:12:0)
[19:52:39.517] Timer tick, asking Buildbucket for the build status
[19:52:39.618] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:39.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:13:0) after 1m0s
[19:53:39.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:13:0)
[19:53:39.741] Timer tick, asking Buildbucket for the build status
[19:53:39.817] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:39.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:14:0) after 1m0s
[19:54:39.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:14:0)
[19:54:39.848] Timer tick, asking Buildbucket for the build status
[19:54:39.977] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:39.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:15:0) after 1m0s
[19:55:40.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:15:0)
[19:55:40.104] Timer tick, asking Buildbucket for the build status
[19:55:40.201] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:40.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:16:0) after 1m0s
[19:56:40.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:16:0)
[19:56:40.361] Timer tick, asking Buildbucket for the build status
[19:56:40.754] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:40.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:17:0) after 1m0s
[19:57:40.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:17:0)
[19:57:40.774] Timer tick, asking Buildbucket for the build status
[19:57:40.890] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:40.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:18:0) after 1m0s
[19:58:40.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:18:0)
[19:58:40.987] Timer tick, asking Buildbucket for the build status
[19:58:41.101] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:41.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:19:0) after 1m0s
[19:59:41.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:19:0)
[19:59:41.131] Timer tick, asking Buildbucket for the build status
[19:59:41.695] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:41.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:20:0) after 1m0s
[20:00:41.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:20:0)
[20:00:41.777] Timer tick, asking Buildbucket for the build status
[20:00:41.831] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:41.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:21:0) after 1m0s
[20:01:42.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:21:0)
[20:01:42.181] Timer tick, asking Buildbucket for the build status
[20:01:42.258] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:42.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:22:0) after 1m0s
[20:02:42.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:22:0)
[20:02:42.590] Timer tick, asking Buildbucket for the build status
[20:02:42.685] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:42.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:23:0) after 1m0s
[20:03:42.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:23:0)
[20:03:42.865] Timer tick, asking Buildbucket for the build status
[20:03:43.066] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:43.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:24:0) after 1m0s
[20:04:43.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:24:0)
[20:04:43.283] Timer tick, asking Buildbucket for the build status
[20:04:43.571] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:43.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:25:0) after 1m0s
[20:05:43.557] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:25:0)
[20:05:43.557] Timer tick, asking Buildbucket for the build status
[20:05:43.789] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:43.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:26:0) after 1m0s
[20:06:43.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:26:0)
[20:06:43.976] Timer tick, asking Buildbucket for the build status
[20:06:44.027] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:44.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:27:0) after 1m0s
[20:07:44.081] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:27:0)
[20:07:44.081] Timer tick, asking Buildbucket for the build status
[20:07:44.150] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:44.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:28:0) after 1m0s
[20:08:44.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:28:0)
[20:08:44.212] Timer tick, asking Buildbucket for the build status
[20:08:44.409] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:44.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:29:0) after 1m0s
[20:09:44.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:29:0)
[20:09:44.543] Timer tick, asking Buildbucket for the build status
[20:09:44.615] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:44.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:30:0) after 1m0s
[20:10:44.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:30:0)
[20:10:44.692] Timer tick, asking Buildbucket for the build status
[20:10:44.945] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:44.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:31:0) after 1m0s
[20:11:45.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:31:0)
[20:11:45.106] Timer tick, asking Buildbucket for the build status
[20:11:45.159] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:45.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:32:0) after 1m0s
[20:12:45.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:32:0)
[20:12:45.212] Timer tick, asking Buildbucket for the build status
[20:12:45.467] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:45.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:33:0) after 1m0s
[20:13:45.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:33:0)
[20:13:45.584] Timer tick, asking Buildbucket for the build status
[20:13:45.639] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:45.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:34:0) after 1m0s
[20:14:45.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:34:0)
[20:14:45.853] Timer tick, asking Buildbucket for the build status
[20:14:45.929] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:45.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:35:0) after 1m0s
[20:15:46.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:35:0)
[20:15:46.049] Timer tick, asking Buildbucket for the build status
[20:15:46.192] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:46.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:36:0) after 1m0s
[20:16:46.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:36:0)
[20:16:46.281] Timer tick, asking Buildbucket for the build status
[20:16:46.341] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:46.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:37:0) after 1m0s
[20:17:46.370] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:37:0)
[20:17:46.370] Timer tick, asking Buildbucket for the build status
[20:17:46.468] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:46.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:38:0) after 1m0s
[20:18:46.545] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:38:0)
[20:18:46.545] Timer tick, asking Buildbucket for the build status
[20:18:46.633] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:46.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:39:0) after 1m0s
[20:19:46.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:39:0)
[20:19:46.674] Timer tick, asking Buildbucket for the build status
[20:19:46.991] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:46.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:40:0) after 1m0s
[20:20:47.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:40:0)
[20:20:47.073] Timer tick, asking Buildbucket for the build status
[20:20:47.204] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:47.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:41:0) after 1m0s
[20:21:47.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:41:0)
[20:21:47.259] Timer tick, asking Buildbucket for the build status
[20:21:47.368] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:47.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:42:0) after 1m0s
[20:22:47.418] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:42:0)
[20:22:47.418] Timer tick, asking Buildbucket for the build status
[20:22:47.535] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:47.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:43:0) after 1m0s
[20:23:47.626] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:43:0)
[20:23:47.626] Timer tick, asking Buildbucket for the build status
[20:23:47.772] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:47.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:44:0) after 1m0s
[20:24:47.846] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:44:0)
[20:24:47.846] Timer tick, asking Buildbucket for the build status
[20:24:47.906] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:47.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:45:0) after 1m0s
[20:25:47.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:45:0)
[20:25:47.916] Timer tick, asking Buildbucket for the build status
[20:25:48.313] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:48.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:46:0) after 1m0s
[20:26:48.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:46:0)
[20:26:48.398] Timer tick, asking Buildbucket for the build status
[20:26:48.551] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:48.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:47:0) after 1m0s
[20:27:48.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:47:0)
[20:27:48.577] Timer tick, asking Buildbucket for the build status
[20:27:48.740] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:48.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:48:0) after 1m0s
[20:28:48.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:48:0)
[20:28:48.766] Timer tick, asking Buildbucket for the build status
[20:28:49.166] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:49.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:49:0) after 1m0s
[20:29:49.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:49:0)
[20:29:49.342] Timer tick, asking Buildbucket for the build status
[20:29:49.419] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:49.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:50:0) after 1m0s
[20:30:49.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:50:0)
[20:30:49.434] Timer tick, asking Buildbucket for the build status
[20:30:50.029] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:50.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:51:0) after 1m0s
[20:31:50.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:51:0)
[20:31:50.157] Timer tick, asking Buildbucket for the build status
[20:31:50.843] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:50.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:52:0) after 1m0s
[20:32:50.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:52:0)
[20:32:50.899] Timer tick, asking Buildbucket for the build status
[20:32:51.562] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:51.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:53:0) after 1m0s
[20:33:51.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:53:0)
[20:33:51.711] Timer tick, asking Buildbucket for the build status
[20:33:52.139] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:52.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:54:0) after 1m0s
[20:34:52.333] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:54:0)
[20:34:52.333] Timer tick, asking Buildbucket for the build status
[20:34:52.620] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:52.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:55:0) after 1m0s
[20:35:52.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:55:0)
[20:35:52.644] Timer tick, asking Buildbucket for the build status
[20:35:52.741] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:52.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:56:0) after 1m0s
[20:36:52.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:56:0)
[20:36:52.760] Timer tick, asking Buildbucket for the build status
[20:36:52.812] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:52.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:57:0) after 1m0s
[20:37:52.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:57:0)
[20:37:52.892] Timer tick, asking Buildbucket for the build status
[20:37:52.980] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:52.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:58:0) after 1m0s
[20:38:53.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:58:0)
[20:38:53.019] Timer tick, asking Buildbucket for the build status
[20:38:53.114] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:53.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:59:0) after 1m0s
[20:39:53.165] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:59:0)
[20:39:53.165] Timer tick, asking Buildbucket for the build status
[20:39:53.227] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:53.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:60:0) after 1m0s
[20:40:53.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:60:0)
[20:40:53.286] Timer tick, asking Buildbucket for the build status
[20:40:53.915] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:53.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:61:0) after 1m0s
[20:41:53.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:61:0)
[20:41:53.948] Timer tick, asking Buildbucket for the build status
[20:41:54.577] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:54.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:62:0) after 1m0s
[20:42:54.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:62:0)
[20:42:54.737] Timer tick, asking Buildbucket for the build status
[20:42:55.186] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:55.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:63:0) after 1m0s
[20:43:55.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:63:0)
[20:43:55.192] Timer tick, asking Buildbucket for the build status
[20:43:55.832] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:55.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:64:0) after 1m0s
[20:44:55.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:64:0)
[20:44:55.876] Timer tick, asking Buildbucket for the build status
[20:44:56.042] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:56.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:65:0) after 1m0s
[20:45:56.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:65:0)
[20:45:56.074] Timer tick, asking Buildbucket for the build status
[20:45:56.132] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:56.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:66:0) after 1m0s
[20:46:56.179] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:66:0)
[20:46:56.179] Timer tick, asking Buildbucket for the build status
[20:46:56.340] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:56.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:67:0) after 1m0s
[20:47:56.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:67:0)
[20:47:56.363] Timer tick, asking Buildbucket for the build status
[20:47:56.671] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:56.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:68:0) after 1m0s
[20:48:56.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:68:0)
[20:48:56.732] Timer tick, asking Buildbucket for the build status
[20:48:56.777] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:56.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:69:0) after 1m0s
[20:49:57.137] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:69:0)
[20:49:57.137] Timer tick, asking Buildbucket for the build status
[20:49:57.416] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:57.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:70:0) after 1m0s
[20:50:58.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:70:0)
[20:50:58.327] Timer tick, asking Buildbucket for the build status
[20:50:58.476] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:58.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:71:0) after 1m0s
[20:51:58.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:71:0)
[20:51:58.509] Timer tick, asking Buildbucket for the build status
[20:51:58.857] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:58.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:72:0) after 1m0s
[20:52:58.939] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:72:0)
[20:52:58.939] Timer tick, asking Buildbucket for the build status
[20:52:59.381] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:59.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:73:0) after 1m0s
[20:53:59.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:73:0)
[20:53:59.490] Timer tick, asking Buildbucket for the build status
[20:53:59.548] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:59.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:74:0) after 1m0s
[20:54:59.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:74:0)
[20:54:59.622] Timer tick, asking Buildbucket for the build status
[20:54:59.989] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:59.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:75:0) after 1m0s
[20:56:00.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:75:0)
[20:56:00.123] Timer tick, asking Buildbucket for the build status
[20:56:00.215] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:00.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:76:0) after 1m0s
[20:57:00.227] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:76:0)
[20:57:00.227] Timer tick, asking Buildbucket for the build status
[20:57:00.636] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:00.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:77:0) after 1m0s
[20:58:00.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:77:0)
[20:58:00.656] Timer tick, asking Buildbucket for the build status
[20:58:00.841] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:00.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:78:0) after 1m0s
[20:59:00.924] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:78:0)
[20:59:00.924] Timer tick, asking Buildbucket for the build status
[20:59:01.096] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:01.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:79:0) after 1m0s
[21:00:01.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:79:0)
[21:00:01.231] Timer tick, asking Buildbucket for the build status
[21:00:01.310] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:01.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:80:0) after 1m0s
[21:01:01.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:80:0)
[21:01:01.533] Timer tick, asking Buildbucket for the build status
[21:01:01.582] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:01.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:81:0) after 1m0s
[21:02:01.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:81:0)
[21:02:01.973] Timer tick, asking Buildbucket for the build status
[21:02:02.075] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:02.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:82:0) after 1m0s
[21:03:02.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:82:0)
[21:03:02.171] Timer tick, asking Buildbucket for the build status
[21:03:02.767] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:02.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:83:0) after 1m0s
[21:04:02.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:83:0)
[21:04:02.809] Timer tick, asking Buildbucket for the build status
[21:04:03.327] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:03.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:84:0) after 1m0s
[21:05:03.356] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:84:0)
[21:05:03.356] Timer tick, asking Buildbucket for the build status
[21:05:03.410] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:03.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:85:0) after 1m0s
[21:06:03.520] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:85:0)
[21:06:03.520] Timer tick, asking Buildbucket for the build status
[21:06:04.056] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:04.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:86:0) after 1m0s
[21:07:04.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:86:0)
[21:07:04.111] Timer tick, asking Buildbucket for the build status
[21:07:05.255] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:05.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:87:0) after 1m0s
[21:08:05.288] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:87:0)
[21:08:05.288] Timer tick, asking Buildbucket for the build status
[21:08:05.527] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:05.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:88:0) after 1m0s
[21:09:05.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:88:0)
[21:09:05.558] Timer tick, asking Buildbucket for the build status
[21:09:05.879] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:05.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:89:0) after 1m0s
[21:10:05.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:89:0)
[21:10:05.900] Timer tick, asking Buildbucket for the build status
[21:10:06.292] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:06.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:90:0) after 1m0s
[21:11:06.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:90:0)
[21:11:06.351] Timer tick, asking Buildbucket for the build status
[21:11:06.447] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:06.447] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:91:0) after 1m0s
[21:12:06.472] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:91:0)
[21:12:06.472] Timer tick, asking Buildbucket for the build status
[21:12:06.692] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:06.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:92:0) after 1m0s
[21:13:06.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:92:0)
[21:13:06.730] Timer tick, asking Buildbucket for the build status
[21:13:06.809] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:06.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:93:0) after 1m0s
[21:14:06.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:93:0)
[21:14:06.867] Timer tick, asking Buildbucket for the build status
[21:14:07.069] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:07.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:94:0) after 1m0s
[21:15:07.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:94:0)
[21:15:07.095] Timer tick, asking Buildbucket for the build status
[21:15:07.169] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:07.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:95:0) after 1m0s
[21:16:07.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:95:0)
[21:16:07.197] Timer tick, asking Buildbucket for the build status
[21:16:07.702] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:07.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:96:0) after 1m0s
[21:17:07.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:96:0)
[21:17:07.793] Timer tick, asking Buildbucket for the build status
[21:17:08.013] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:08.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:97:0) after 1m0s
[21:18:10.252] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:97:0)
[21:18:10.252] Timer tick, asking Buildbucket for the build status
[21:18:14.811] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:14.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:98:0) after 1m0s
[21:19:15.060] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:98:0)
[21:19:15.060] Timer tick, asking Buildbucket for the build status
[21:19:15.107] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:15.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:99:0) after 1m0s
[21:20:15.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:99:0)
[21:20:15.311] Timer tick, asking Buildbucket for the build status
[21:20:15.398] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:15.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:100:0) after 1m0s
[21:21:15.456] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:100:0)
[21:21:15.456] Timer tick, asking Buildbucket for the build status
[21:21:15.842] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:15.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:101:0) after 1m0s
[21:22:15.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:101:0)
[21:22:15.938] Timer tick, asking Buildbucket for the build status
[21:22:16.599] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:16.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:102:0) after 1m0s
[21:23:16.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:102:0)
[21:23:16.656] Timer tick, asking Buildbucket for the build status
[21:23:16.829] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:16.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:103:0) after 1m0s
[21:24:16.931] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:103:0)
[21:24:16.931] Timer tick, asking Buildbucket for the build status
[21:24:17.299] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:17.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:104:0) after 1m0s
[21:25:17.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:104:0)
[21:25:17.348] Timer tick, asking Buildbucket for the build status
[21:25:17.429] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:17.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:105:0) after 1m0s
[21:26:17.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:105:0)
[21:26:17.468] Timer tick, asking Buildbucket for the build status
[21:26:17.527] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:17.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:106:0) after 1m0s
[21:27:17.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:106:0)
[21:27:17.552] Timer tick, asking Buildbucket for the build status
[21:27:17.851] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:17.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:107:0) after 1m0s
[21:28:17.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:107:0)
[21:28:17.951] Timer tick, asking Buildbucket for the build status
[21:28:18.027] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:18.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:108:0) after 1m0s
[21:29:18.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:108:0)
[21:29:18.085] Timer tick, asking Buildbucket for the build status
[21:29:18.873] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:18.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:109:0) after 1m0s
[21:30:18.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:109:0)
[21:30:18.929] Timer tick, asking Buildbucket for the build status
[21:30:19.024] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:19.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:110:0) after 1m0s
[21:31:19.060] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:110:0)
[21:31:19.060] Timer tick, asking Buildbucket for the build status
[21:31:19.132] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:19.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:111:0) after 1m0s
[21:32:19.158] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:111:0)
[21:32:19.158] Timer tick, asking Buildbucket for the build status
[21:32:19.641] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:19.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:112:0) after 1m0s
[21:33:19.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:112:0)
[21:33:19.687] Timer tick, asking Buildbucket for the build status
[21:33:19.752] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:19.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:113:0) after 1m0s
[21:34:19.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:113:0)
[21:34:19.754] Timer tick, asking Buildbucket for the build status
[21:34:19.895] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:19.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:114:0) after 1m0s
[21:35:19.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:114:0)
[21:35:19.987] Timer tick, asking Buildbucket for the build status
[21:35:20.596] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:20.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:115:0) after 1m0s
[21:36:20.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:115:0)
[21:36:20.596] Timer tick, asking Buildbucket for the build status
[21:36:20.893] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:20.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:116:0) after 1m0s
[21:37:21.003] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:116:0)
[21:37:21.003] Timer tick, asking Buildbucket for the build status
[21:37:21.635] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:21.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:117:0) after 1m0s
[21:38:21.740] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:117:0)
[21:38:21.740] Timer tick, asking Buildbucket for the build status
[21:38:21.848] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:21.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:118:0) after 1m0s
[21:39:21.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:118:0)
[21:39:21.926] Timer tick, asking Buildbucket for the build status
[21:39:21.990] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:21.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:119:0) after 1m0s
[21:40:22.014] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:119:0)
[21:40:22.014] Timer tick, asking Buildbucket for the build status
[21:40:22.737] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:22.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:120:0) after 1m0s
[21:41:22.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:120:0)
[21:41:22.818] Timer tick, asking Buildbucket for the build status
[21:41:22.898] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:22.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:121:0) after 1m0s
[21:42:22.915] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:121:0)
[21:42:22.915] Timer tick, asking Buildbucket for the build status
[21:42:23.296] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:23.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:122:0) after 1m0s
[21:43:23.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:122:0)
[21:43:23.417] Timer tick, asking Buildbucket for the build status
[21:43:23.556] Build 8912640637707943008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:23.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078070699970353104:123:0) after 1m0s
[21:43:32.832] Received PubSub notification, asking Buildbucket for the build status
[21:43:33.272] Build 8912640637707943008: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:43:33.272] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558647812121388",
"created_by": "project:fuchsia",
"created_ts": "1558640554667401",
"failure_reason": "BUILD_FAILURE",
"id": "8912640637707943008",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"cb64cd9b6041c2c8aaca6d01023e5d7963873701\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-c-2-xnbw\", \"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\": \"cb64cd9b6041c2c8aaca6d01023e5d7963873701\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-2-xnbw\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1558640571129809",
"status": "COMPLETED",
"status_changed_ts": "1558640573391001",
"tags": [
"builder:clang",
"buildset:commit/git/cb64cd9b6041c2c8aaca6d01023e5d7963873701",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/cb64cd9b6041c2c8aaca6d01023e5d7963873701",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078070699970353104",
"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/8912640637707943008/+/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:44ff090b17e96e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558647812345700",
"url": "https://ci.chromium.org/b/8912640637707943008",
"utcnow_ts": "1558647813153032"
}
}
[21:43:33.272] Invocation finished in 2h1m0.146324463s with status FAILED