[21:17:12.073] New invocation is queued and will start shortly
[21:17:13.181] Starting the invocation (attempt 1)
[21:17:13.221] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:17:13.221] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:17:13.221] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083681757099522016",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"8316ea42992f5707ac63d633aa6ebc8ab73970e8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083681757099522016",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/8316ea42992f5707ac63d633aa6ebc8ab73970e8",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/8316ea42992f5707ac63d633aa6ebc8ab73970e8",
"gitiles_ref:refs/heads/master"
]
}
[21:17:14.751] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553289433335502",
"id": "8918251695110092208",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"8316ea42992f5707ac63d633aa6ebc8ab73970e8\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553289434479318",
"tags": [
"builder:clang",
"buildset:commit/git/8316ea42992f5707ac63d633aa6ebc8ab73970e8",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/8316ea42992f5707ac63d633aa6ebc8ab73970e8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083681757099522016",
"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/8918251695110092208/+/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:43c0155a69264510",
"user_agent:luci-scheduler"
],
"updated_ts": "1553289434479414",
"url": "https://ci.chromium.org/b/8918251695110092208",
"utcnow_ts": "1553289434742253"
}
}
[21:17:14.751] Task URL: https://ci.chromium.org/b/8918251695110092208
[21:17:14.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:2:0) after 1m0s
[21:17:17.243] Received PubSub notification, asking Buildbucket for the build status
[21:17:17.305] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:14.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:2:0)
[21:18:14.778] Timer tick, asking Buildbucket for the build status
[21:18:14.866] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:14.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:4:0) after 1m0s
[21:19:14.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:4:0)
[21:19:14.889] Timer tick, asking Buildbucket for the build status
[21:19:15.006] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:15.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:5:0) after 1m0s
[21:20:15.025] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:5:0)
[21:20:15.025] Timer tick, asking Buildbucket for the build status
[21:20:15.412] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:15.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:6:0) after 1m0s
[21:21:15.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:6:0)
[21:21:15.410] Timer tick, asking Buildbucket for the build status
[21:21:15.454] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:15.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:7:0) after 1m0s
[21:22:15.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:7:0)
[21:22:15.517] Timer tick, asking Buildbucket for the build status
[21:22:15.826] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:15.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:8:0) after 1m0s
[21:23:15.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:8:0)
[21:23:15.850] Timer tick, asking Buildbucket for the build status
[21:23:15.921] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:15.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:9:0) after 1m0s
[21:24:15.939] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:9:0)
[21:24:15.939] Timer tick, asking Buildbucket for the build status
[21:24:15.986] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:15.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:10:0) after 1m0s
[21:25:15.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:10:0)
[21:25:15.986] Timer tick, asking Buildbucket for the build status
[21:25:16.046] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:16.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:11:0) after 1m0s
[21:26:16.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:11:0)
[21:26:16.067] Timer tick, asking Buildbucket for the build status
[21:26:16.190] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:16.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:12:0) after 1m0s
[21:27:16.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:12:0)
[21:27:16.183] Timer tick, asking Buildbucket for the build status
[21:27:16.363] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:16.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:13:0) after 1m0s
[21:28:16.352] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:13:0)
[21:28:16.352] Timer tick, asking Buildbucket for the build status
[21:28:16.466] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:16.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:14:0) after 1m0s
[21:29:16.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:14:0)
[21:29:16.489] Timer tick, asking Buildbucket for the build status
[21:29:16.558] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:16.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:15:0) after 1m0s
[21:30:16.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:15:0)
[21:30:16.622] Timer tick, asking Buildbucket for the build status
[21:30:16.696] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:16.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:16:0) after 1m0s
[21:31:16.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:16:0)
[21:31:16.704] Timer tick, asking Buildbucket for the build status
[21:31:16.780] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:16.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:17:0) after 1m0s
[21:32:16.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:17:0)
[21:32:16.801] Timer tick, asking Buildbucket for the build status
[21:32:16.864] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:16.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:18:0) after 1m0s
[21:33:16.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:18:0)
[21:33:16.894] Timer tick, asking Buildbucket for the build status
[21:33:16.955] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:16.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:19:0) after 1m0s
[21:34:16.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:19:0)
[21:34:16.978] Timer tick, asking Buildbucket for the build status
[21:34:17.040] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:17.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:20:0) after 1m0s
[21:35:17.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:20:0)
[21:35:17.035] Timer tick, asking Buildbucket for the build status
[21:35:17.088] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:17.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:21:0) after 1m0s
[21:36:17.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:21:0)
[21:36:17.203] Timer tick, asking Buildbucket for the build status
[21:36:17.303] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:17.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:22:0) after 1m0s
[21:37:17.310] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:22:0)
[21:37:17.310] Timer tick, asking Buildbucket for the build status
[21:37:17.352] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:17.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:23:0) after 1m0s
[21:38:17.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:23:0)
[21:38:17.580] Timer tick, asking Buildbucket for the build status
[21:38:17.678] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:17.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:24:0) after 1m0s
[21:39:17.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:24:0)
[21:39:17.699] Timer tick, asking Buildbucket for the build status
[21:39:17.768] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:17.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:25:0) after 1m0s
[21:40:17.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:25:0)
[21:40:17.766] Timer tick, asking Buildbucket for the build status
[21:40:17.825] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:17.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:26:0) after 1m0s
[21:41:17.816] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:26:0)
[21:41:17.816] Timer tick, asking Buildbucket for the build status
[21:41:17.969] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:17.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:27:0) after 1m0s
[21:42:17.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:27:0)
[21:42:17.989] Timer tick, asking Buildbucket for the build status
[21:42:18.065] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:18.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:28:0) after 1m0s
[21:43:18.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:28:0)
[21:43:18.094] Timer tick, asking Buildbucket for the build status
[21:43:18.162] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:18.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:29:0) after 1m0s
[21:44:18.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:29:0)
[21:44:18.180] Timer tick, asking Buildbucket for the build status
[21:44:18.229] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:18.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:30:0) after 1m0s
[21:45:18.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:30:0)
[21:45:18.201] Timer tick, asking Buildbucket for the build status
[21:45:18.403] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:18.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:31:0) after 1m0s
[21:46:18.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:31:0)
[21:46:18.421] Timer tick, asking Buildbucket for the build status
[21:46:18.520] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:18.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:32:0) after 1m0s
[21:47:18.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:32:0)
[21:47:18.506] Timer tick, asking Buildbucket for the build status
[21:47:18.564] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:18.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:33:0) after 1m0s
[21:48:18.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:33:0)
[21:48:18.589] Timer tick, asking Buildbucket for the build status
[21:48:18.662] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:18.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:34:0) after 1m0s
[21:49:18.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:34:0)
[21:49:18.699] Timer tick, asking Buildbucket for the build status
[21:49:18.737] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:18.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:35:0) after 1m0s
[21:50:18.714] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:35:0)
[21:50:18.714] Timer tick, asking Buildbucket for the build status
[21:50:18.790] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:18.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:36:0) after 1m0s
[21:51:18.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:36:0)
[21:51:18.803] Timer tick, asking Buildbucket for the build status
[21:51:19.182] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:19.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:37:0) after 1m0s
[21:52:19.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:37:0)
[21:52:19.207] Timer tick, asking Buildbucket for the build status
[21:52:19.313] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:19.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:38:0) after 1m0s
[21:53:19.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:38:0)
[21:53:19.338] Timer tick, asking Buildbucket for the build status
[21:53:19.406] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:19.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:39:0) after 1m0s
[21:54:19.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:39:0)
[21:54:19.421] Timer tick, asking Buildbucket for the build status
[21:54:19.493] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:19.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:40:0) after 1m0s
[21:55:19.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:40:0)
[21:55:19.507] Timer tick, asking Buildbucket for the build status
[21:55:19.608] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:19.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:41:0) after 1m0s
[21:56:19.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:41:0)
[21:56:19.588] Timer tick, asking Buildbucket for the build status
[21:56:19.763] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:19.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:42:0) after 1m0s
[21:57:19.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:42:0)
[21:57:19.780] Timer tick, asking Buildbucket for the build status
[21:57:21.797] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:21.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:43:0) after 1m0s
[21:58:21.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:43:0)
[21:58:21.798] Timer tick, asking Buildbucket for the build status
[21:58:21.883] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:21.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:44:0) after 1m0s
[21:59:21.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:44:0)
[21:59:21.886] Timer tick, asking Buildbucket for the build status
[21:59:21.931] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:21.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:45:0) after 1m0s
[22:00:21.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:45:0)
[22:00:21.950] Timer tick, asking Buildbucket for the build status
[22:00:22.022] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:22.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:46:0) after 1m0s
[22:01:22.024] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:46:0)
[22:01:22.024] Timer tick, asking Buildbucket for the build status
[22:01:22.076] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:22.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:47:0) after 1m0s
[22:02:22.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:47:0)
[22:02:22.105] Timer tick, asking Buildbucket for the build status
[22:02:22.181] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:22.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:48:0) after 1m0s
[22:03:22.233] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:48:0)
[22:03:22.233] Timer tick, asking Buildbucket for the build status
[22:03:22.351] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:22.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:49:0) after 1m0s
[22:04:22.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:49:0)
[22:04:22.331] Timer tick, asking Buildbucket for the build status
[22:04:22.382] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:22.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:50:0) after 1m0s
[22:05:22.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:50:0)
[22:05:22.399] Timer tick, asking Buildbucket for the build status
[22:05:22.470] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:22.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:51:0) after 1m0s
[22:06:22.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:51:0)
[22:06:22.478] Timer tick, asking Buildbucket for the build status
[22:06:22.526] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:22.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:52:0) after 1m0s
[22:07:22.550] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:52:0)
[22:07:22.550] Timer tick, asking Buildbucket for the build status
[22:07:22.622] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:22.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:53:0) after 1m0s
[22:08:22.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:53:0)
[22:08:22.598] Timer tick, asking Buildbucket for the build status
[22:08:22.681] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:22.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:54:0) after 1m0s
[22:09:22.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:54:0)
[22:09:22.662] Timer tick, asking Buildbucket for the build status
[22:09:22.712] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:22.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:55:0) after 1m0s
[22:10:22.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:55:0)
[22:10:22.722] Timer tick, asking Buildbucket for the build status
[22:10:22.778] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:22.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:56:0) after 1m0s
[22:11:22.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:56:0)
[22:11:22.798] Timer tick, asking Buildbucket for the build status
[22:11:22.865] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:22.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:57:0) after 1m0s
[22:12:22.885] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:57:0)
[22:12:22.885] Timer tick, asking Buildbucket for the build status
[22:12:23.002] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:23.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:58:0) after 1m0s
[22:13:23.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:58:0)
[22:13:23.026] Timer tick, asking Buildbucket for the build status
[22:13:23.082] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:23.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:59:0) after 1m0s
[22:14:23.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:59:0)
[22:14:23.116] Timer tick, asking Buildbucket for the build status
[22:14:23.260] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:23.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:60:0) after 1m0s
[22:15:23.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:60:0)
[22:15:23.295] Timer tick, asking Buildbucket for the build status
[22:15:23.429] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:23.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:61:0) after 1m0s
[22:16:23.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:61:0)
[22:16:23.465] Timer tick, asking Buildbucket for the build status
[22:16:23.507] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:23.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:62:0) after 1m0s
[22:17:23.527] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:62:0)
[22:17:23.527] Timer tick, asking Buildbucket for the build status
[22:17:23.628] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:23.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:63:0) after 1m0s
[22:18:23.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:63:0)
[22:18:23.628] Timer tick, asking Buildbucket for the build status
[22:18:23.685] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:23.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:64:0) after 1m0s
[22:19:23.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:64:0)
[22:19:23.656] Timer tick, asking Buildbucket for the build status
[22:19:23.805] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:23.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:65:0) after 1m0s
[22:20:23.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:65:0)
[22:20:23.819] Timer tick, asking Buildbucket for the build status
[22:20:23.948] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:23.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:66:0) after 1m0s
[22:21:23.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:66:0)
[22:21:23.943] Timer tick, asking Buildbucket for the build status
[22:21:23.995] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:23.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:67:0) after 1m0s
[22:22:24.007] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:67:0)
[22:22:24.007] Timer tick, asking Buildbucket for the build status
[22:22:24.568] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:24.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:68:0) after 1m0s
[22:23:24.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:68:0)
[22:23:24.551] Timer tick, asking Buildbucket for the build status
[22:23:24.735] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:24.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:69:0) after 1m0s
[22:24:24.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:69:0)
[22:24:24.755] Timer tick, asking Buildbucket for the build status
[22:24:24.858] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:24.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:70:0) after 1m0s
[22:25:24.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:70:0)
[22:25:24.880] Timer tick, asking Buildbucket for the build status
[22:25:24.962] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:24.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:71:0) after 1m0s
[22:26:24.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:71:0)
[22:26:24.983] Timer tick, asking Buildbucket for the build status
[22:26:25.032] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:25.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:72:0) after 1m0s
[22:27:25.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:72:0)
[22:27:25.009] Timer tick, asking Buildbucket for the build status
[22:27:25.057] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:25.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:73:0) after 1m0s
[22:28:25.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:73:0)
[22:28:25.084] Timer tick, asking Buildbucket for the build status
[22:28:25.127] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:25.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:74:0) after 1m0s
[22:29:25.110] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:74:0)
[22:29:25.110] Timer tick, asking Buildbucket for the build status
[22:29:25.171] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:25.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:75:0) after 1m0s
[22:30:25.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:75:0)
[22:30:25.190] Timer tick, asking Buildbucket for the build status
[22:30:25.248] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:25.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:76:0) after 1m0s
[22:31:25.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:76:0)
[22:31:25.268] Timer tick, asking Buildbucket for the build status
[22:31:25.367] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:25.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:77:0) after 1m0s
[22:32:25.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:77:0)
[22:32:25.392] Timer tick, asking Buildbucket for the build status
[22:32:25.443] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:25.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:78:0) after 1m0s
[22:33:25.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:78:0)
[22:33:25.463] Timer tick, asking Buildbucket for the build status
[22:33:25.518] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:25.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:79:0) after 1m0s
[22:34:25.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:79:0)
[22:34:25.537] Timer tick, asking Buildbucket for the build status
[22:34:25.589] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:25.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:80:0) after 1m0s
[22:35:25.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:80:0)
[22:35:25.608] Timer tick, asking Buildbucket for the build status
[22:35:25.682] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:25.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:81:0) after 1m0s
[22:36:25.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:81:0)
[22:36:25.694] Timer tick, asking Buildbucket for the build status
[22:36:25.988] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:25.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:82:0) after 1m0s
[22:37:25.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:82:0)
[22:37:25.998] Timer tick, asking Buildbucket for the build status
[22:37:26.495] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:26.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:83:0) after 1m0s
[22:38:26.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:83:0)
[22:38:26.568] Timer tick, asking Buildbucket for the build status
[22:38:26.646] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:26.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:84:0) after 1m0s
[22:39:26.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:84:0)
[22:39:26.665] Timer tick, asking Buildbucket for the build status
[22:39:26.702] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:26.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:85:0) after 1m0s
[22:40:26.729] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:85:0)
[22:40:26.729] Timer tick, asking Buildbucket for the build status
[22:40:26.803] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:26.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:86:0) after 1m0s
[22:41:26.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:86:0)
[22:41:26.823] Timer tick, asking Buildbucket for the build status
[22:41:26.905] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:26.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:87:0) after 1m0s
[22:42:26.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:87:0)
[22:42:26.914] Timer tick, asking Buildbucket for the build status
[22:42:27.013] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:27.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:88:0) after 1m0s
[22:43:26.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:88:0)
[22:43:26.999] Timer tick, asking Buildbucket for the build status
[22:43:27.093] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:27.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:89:0) after 1m0s
[22:44:27.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:89:0)
[22:44:27.107] Timer tick, asking Buildbucket for the build status
[22:44:27.276] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:27.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:90:0) after 1m0s
[22:45:27.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:90:0)
[22:45:27.368] Timer tick, asking Buildbucket for the build status
[22:45:27.616] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:27.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:91:0) after 1m0s
[22:46:27.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:91:0)
[22:46:27.605] Timer tick, asking Buildbucket for the build status
[22:46:27.641] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:27.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:92:0) after 1m0s
[22:47:27.639] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:92:0)
[22:47:27.639] Timer tick, asking Buildbucket for the build status
[22:47:27.725] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:27.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:93:0) after 1m0s
[22:48:27.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:93:0)
[22:48:27.745] Timer tick, asking Buildbucket for the build status
[22:48:27.795] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:27.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:94:0) after 1m0s
[22:49:27.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:94:0)
[22:49:27.770] Timer tick, asking Buildbucket for the build status
[22:49:27.887] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:27.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:95:0) after 1m0s
[22:50:27.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:95:0)
[22:50:27.869] Timer tick, asking Buildbucket for the build status
[22:50:28.168] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:28.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:96:0) after 1m0s
[22:51:28.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:96:0)
[22:51:28.171] Timer tick, asking Buildbucket for the build status
[22:51:28.299] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:28.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:97:0) after 1m0s
[22:52:28.275] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:97:0)
[22:52:28.275] Timer tick, asking Buildbucket for the build status
[22:52:28.315] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:28.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:98:0) after 1m0s
[22:53:28.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:98:0)
[22:53:28.296] Timer tick, asking Buildbucket for the build status
[22:53:28.335] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:28.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:99:0) after 1m0s
[22:54:28.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:99:0)
[22:54:28.362] Timer tick, asking Buildbucket for the build status
[22:54:28.492] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:28.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:100:0) after 1m0s
[22:55:28.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:100:0)
[22:55:28.514] Timer tick, asking Buildbucket for the build status
[22:55:28.854] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:28.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:101:0) after 1m0s
[22:56:28.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:101:0)
[22:56:28.875] Timer tick, asking Buildbucket for the build status
[22:56:28.925] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:28.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:102:0) after 1m0s
[22:57:28.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:102:0)
[22:57:28.948] Timer tick, asking Buildbucket for the build status
[22:57:29.050] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:29.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:103:0) after 1m0s
[22:58:29.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:103:0)
[22:58:29.088] Timer tick, asking Buildbucket for the build status
[22:58:29.149] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:29.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:104:0) after 1m0s
[22:59:29.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:104:0)
[22:59:29.135] Timer tick, asking Buildbucket for the build status
[22:59:29.173] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:29.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:105:0) after 1m0s
[23:00:29.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:105:0)
[23:00:29.153] Timer tick, asking Buildbucket for the build status
[23:00:29.208] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:29.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:106:0) after 1m0s
[23:01:29.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:106:0)
[23:01:29.231] Timer tick, asking Buildbucket for the build status
[23:01:29.292] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:29.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:107:0) after 1m0s
[23:02:29.474] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:107:0)
[23:02:29.474] Timer tick, asking Buildbucket for the build status
[23:02:29.600] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:29.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:108:0) after 1m0s
[23:03:29.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:108:0)
[23:03:29.603] Timer tick, asking Buildbucket for the build status
[23:03:29.699] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:29.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:109:0) after 1m0s
[23:04:29.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:109:0)
[23:04:29.718] Timer tick, asking Buildbucket for the build status
[23:04:29.762] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:29.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:110:0) after 1m0s
[23:05:29.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:110:0)
[23:05:29.783] Timer tick, asking Buildbucket for the build status
[23:05:29.833] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:29.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:111:0) after 1m0s
[23:06:29.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:111:0)
[23:06:29.827] Timer tick, asking Buildbucket for the build status
[23:06:29.887] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:29.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:112:0) after 1m0s
[23:07:29.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:112:0)
[23:07:29.909] Timer tick, asking Buildbucket for the build status
[23:07:30.005] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:30.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:113:0) after 1m0s
[23:08:29.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:113:0)
[23:08:29.993] Timer tick, asking Buildbucket for the build status
[23:08:30.288] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:30.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:114:0) after 1m0s
[23:09:30.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:114:0)
[23:09:30.291] Timer tick, asking Buildbucket for the build status
[23:09:30.330] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:30.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:115:0) after 1m0s
[23:10:30.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:115:0)
[23:10:30.306] Timer tick, asking Buildbucket for the build status
[23:10:30.348] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:30.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:116:0) after 1m0s
[23:11:30.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:116:0)
[23:11:30.371] Timer tick, asking Buildbucket for the build status
[23:11:30.447] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:30.447] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:117:0) after 1m0s
[23:12:30.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:117:0)
[23:12:30.491] Timer tick, asking Buildbucket for the build status
[23:12:31.021] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:31.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:118:0) after 1m0s
[23:13:31.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:118:0)
[23:13:31.044] Timer tick, asking Buildbucket for the build status
[23:13:31.082] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:31.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:119:0) after 1m0s
[23:14:31.100] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:119:0)
[23:14:31.100] Timer tick, asking Buildbucket for the build status
[23:14:31.211] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:31.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:120:0) after 1m0s
[23:15:31.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:120:0)
[23:15:31.251] Timer tick, asking Buildbucket for the build status
[23:15:31.328] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:31.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:121:0) after 1m0s
[23:16:31.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:121:0)
[23:16:31.304] Timer tick, asking Buildbucket for the build status
[23:16:31.345] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:31.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:122:0) after 1m0s
[23:17:31.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:122:0)
[23:17:31.347] Timer tick, asking Buildbucket for the build status
[23:17:31.403] Build 8918251695110092208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:31.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083681757099522016:123:0) after 1m0s
[23:17:40.546] Received PubSub notification, asking Buildbucket for the build status
[23:17:40.586] Build 8918251695110092208: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:17:40.586] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553296660177228",
"created_by": "project:fuchsia",
"created_ts": "1553289433335502",
"failure_reason": "BUILD_FAILURE",
"id": "8918251695110092208",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"8316ea42992f5707ac63d633aa6ebc8ab73970e8\"}}",
"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-debian-1-7887e815-us-central1-c-wkhp\", \"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\": \"8316ea42992f5707ac63d633aa6ebc8ab73970e8\", \"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-debian-1-7887e815-us-central1-c-wkhp\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"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\": [\"4216-cf4a3cd\"], \"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": "1553289435134365",
"status": "COMPLETED",
"status_changed_ts": "1553289436705764",
"tags": [
"builder:clang",
"buildset:commit/git/8316ea42992f5707ac63d633aa6ebc8ab73970e8",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/8316ea42992f5707ac63d633aa6ebc8ab73970e8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083681757099522016",
"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/8918251695110092208/+/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:43c0155a69264510",
"user_agent:luci-scheduler"
],
"updated_ts": "1553296660274428",
"url": "https://ci.chromium.org/b/8918251695110092208",
"utcnow_ts": "1553296660576392"
}
}
[23:17:40.587] Invocation finished in 2h0m28.532969177s with status FAILED