[16:58:50.181] New invocation is queued and will start shortly
[16:58:51.260] Starting the invocation (attempt 1)
[16:58:51.291] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:58:51.292] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:58:51.292] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080617715153237504",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"ba55767f516af95f8620e95e05e674071a10b6c1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080617715153237504",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/ba55767f516af95f8620e95e05e674071a10b6c1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ba55767f516af95f8620e95e05e674071a10b6c1",
"gitiles_ref:refs/heads/master"
]
}
[16:58:52.292] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556211531371138",
"id": "8915187653239138400",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"ba55767f516af95f8620e95e05e674071a10b6c1\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556211531657588",
"tags": [
"builder:clang",
"buildset:commit/git/ba55767f516af95f8620e95e05e674071a10b6c1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ba55767f516af95f8620e95e05e674071a10b6c1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080617715153237504",
"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/8915187653239138400/+/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": "1556211531657728",
"url": "https://ci.chromium.org/b/8915187653239138400",
"utcnow_ts": "1556211532284449"
}
}
[16:58:52.292] Task URL: https://ci.chromium.org/b/8915187653239138400
[16:58:52.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:2:0) after 1m0s
[16:58:56.397] Received PubSub notification, asking Buildbucket for the build status
[16:58:56.465] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:52.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:2:0)
[16:59:52.316] Timer tick, asking Buildbucket for the build status
[16:59:52.665] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:52.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:4:0) after 1m0s
[17:00:52.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:4:0)
[17:00:52.687] Timer tick, asking Buildbucket for the build status
[17:00:52.800] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:52.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:5:0) after 1m0s
[17:01:52.817] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:5:0)
[17:01:52.817] Timer tick, asking Buildbucket for the build status
[17:01:52.996] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:52.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:6:0) after 1m0s
[17:02:52.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:6:0)
[17:02:52.989] Timer tick, asking Buildbucket for the build status
[17:02:53.150] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:53.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:7:0) after 1m0s
[17:03:53.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:7:0)
[17:03:53.168] Timer tick, asking Buildbucket for the build status
[17:03:53.212] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:53.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:8:0) after 1m0s
[17:04:53.187] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:8:0)
[17:04:53.187] Timer tick, asking Buildbucket for the build status
[17:04:53.276] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:53.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:9:0) after 1m0s
[17:05:53.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:9:0)
[17:05:53.582] Timer tick, asking Buildbucket for the build status
[17:05:53.629] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:53.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:10:0) after 1m0s
[17:06:53.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:10:0)
[17:06:53.644] Timer tick, asking Buildbucket for the build status
[17:06:54.068] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:54.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:11:0) after 1m0s
[17:07:54.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:11:0)
[17:07:54.089] Timer tick, asking Buildbucket for the build status
[17:07:54.163] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:54.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:12:0) after 1m0s
[17:08:54.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:12:0)
[17:08:54.185] Timer tick, asking Buildbucket for the build status
[17:08:54.242] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:54.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:13:0) after 1m0s
[17:09:54.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:13:0)
[17:09:54.259] Timer tick, asking Buildbucket for the build status
[17:09:54.336] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:54.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:14:0) after 1m0s
[17:10:54.357] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:14:0)
[17:10:54.357] Timer tick, asking Buildbucket for the build status
[17:10:54.441] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:54.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:15:0) after 1m0s
[17:11:54.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:15:0)
[17:11:54.468] Timer tick, asking Buildbucket for the build status
[17:11:54.724] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:54.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:16:0) after 1m0s
[17:12:54.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:16:0)
[17:12:54.711] Timer tick, asking Buildbucket for the build status
[17:12:54.875] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:54.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:17:0) after 1m0s
[17:13:54.895] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:17:0)
[17:13:54.895] Timer tick, asking Buildbucket for the build status
[17:13:54.952] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:54.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:18:0) after 1m0s
[17:14:54.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:18:0)
[17:14:54.945] Timer tick, asking Buildbucket for the build status
[17:14:55.021] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:55.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:19:0) after 1m0s
[17:15:55.040] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:19:0)
[17:15:55.040] Timer tick, asking Buildbucket for the build status
[17:15:55.172] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:55.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:20:0) after 1m0s
[17:16:55.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:20:0)
[17:16:55.167] Timer tick, asking Buildbucket for the build status
[17:16:55.448] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:55.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:21:0) after 1m0s
[17:17:55.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:21:0)
[17:17:55.475] Timer tick, asking Buildbucket for the build status
[17:17:55.517] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:55.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:22:0) after 1m0s
[17:18:55.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:22:0)
[17:18:55.543] Timer tick, asking Buildbucket for the build status
[17:18:55.607] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:55.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:23:0) after 1m0s
[17:19:55.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:23:0)
[17:19:55.641] Timer tick, asking Buildbucket for the build status
[17:19:55.770] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:55.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:24:0) after 1m0s
[17:20:55.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:24:0)
[17:20:55.784] Timer tick, asking Buildbucket for the build status
[17:20:55.872] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:55.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:25:0) after 1m0s
[17:21:55.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:25:0)
[17:21:55.912] Timer tick, asking Buildbucket for the build status
[17:21:55.957] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:55.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:26:0) after 1m0s
[17:22:55.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:26:0)
[17:22:55.986] Timer tick, asking Buildbucket for the build status
[17:22:56.081] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:56.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:27:0) after 1m0s
[17:23:56.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:27:0)
[17:23:56.101] Timer tick, asking Buildbucket for the build status
[17:23:56.188] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:56.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:28:0) after 1m0s
[17:24:56.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:28:0)
[17:24:56.238] Timer tick, asking Buildbucket for the build status
[17:24:56.426] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:56.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:29:0) after 1m0s
[17:25:56.474] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:29:0)
[17:25:56.474] Timer tick, asking Buildbucket for the build status
[17:25:56.526] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:56.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:30:0) after 1m0s
[17:26:56.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:30:0)
[17:26:56.509] Timer tick, asking Buildbucket for the build status
[17:26:56.724] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:56.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:31:0) after 1m0s
[17:27:56.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:31:0)
[17:27:56.716] Timer tick, asking Buildbucket for the build status
[17:27:56.770] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:56.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:32:0) after 1m0s
[17:28:56.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:32:0)
[17:28:56.743] Timer tick, asking Buildbucket for the build status
[17:28:56.883] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:56.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:33:0) after 1m0s
[17:29:56.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:33:0)
[17:29:56.902] Timer tick, asking Buildbucket for the build status
[17:29:56.958] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:56.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:34:0) after 1m0s
[17:30:57.018] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:34:0)
[17:30:57.018] Timer tick, asking Buildbucket for the build status
[17:30:57.100] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:57.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:35:0) after 1m0s
[17:31:57.120] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:35:0)
[17:31:57.120] Timer tick, asking Buildbucket for the build status
[17:31:57.198] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:57.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:36:0) after 1m0s
[17:32:57.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:36:0)
[17:32:57.228] Timer tick, asking Buildbucket for the build status
[17:32:57.320] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:57.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:37:0) after 1m0s
[17:33:57.344] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:37:0)
[17:33:57.344] Timer tick, asking Buildbucket for the build status
[17:33:57.408] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:57.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:38:0) after 1m0s
[17:34:57.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:38:0)
[17:34:57.385] Timer tick, asking Buildbucket for the build status
[17:34:57.463] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:57.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:39:0) after 1m0s
[17:35:57.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:39:0)
[17:35:57.482] Timer tick, asking Buildbucket for the build status
[17:35:57.561] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:57.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:40:0) after 1m0s
[17:36:57.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:40:0)
[17:36:57.542] Timer tick, asking Buildbucket for the build status
[17:36:57.738] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:57.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:41:0) after 1m0s
[17:37:57.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:41:0)
[17:37:57.742] Timer tick, asking Buildbucket for the build status
[17:37:57.778] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:57.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:42:0) after 1m0s
[17:38:57.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:42:0)
[17:38:57.783] Timer tick, asking Buildbucket for the build status
[17:38:57.824] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:57.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:43:0) after 1m0s
[17:39:57.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:43:0)
[17:39:57.881] Timer tick, asking Buildbucket for the build status
[17:39:57.958] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:57.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:44:0) after 1m0s
[17:40:57.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:44:0)
[17:40:57.978] Timer tick, asking Buildbucket for the build status
[17:40:58.016] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:58.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:45:0) after 1m0s
[17:41:58.033] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:45:0)
[17:41:58.033] Timer tick, asking Buildbucket for the build status
[17:41:58.097] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:58.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:46:0) after 1m0s
[17:42:58.120] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:46:0)
[17:42:58.120] Timer tick, asking Buildbucket for the build status
[17:42:58.194] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:58.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:47:0) after 1m0s
[17:43:58.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:47:0)
[17:43:58.218] Timer tick, asking Buildbucket for the build status
[17:43:58.274] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:58.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:48:0) after 1m0s
[17:44:58.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:48:0)
[17:44:58.300] Timer tick, asking Buildbucket for the build status
[17:44:58.336] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:58.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:49:0) after 1m0s
[17:45:58.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:49:0)
[17:45:58.385] Timer tick, asking Buildbucket for the build status
[17:45:58.450] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:58.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:50:0) after 1m0s
[17:46:58.422] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:50:0)
[17:46:58.422] Timer tick, asking Buildbucket for the build status
[17:46:58.502] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:58.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:51:0) after 1m0s
[17:47:58.528] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:51:0)
[17:47:58.528] Timer tick, asking Buildbucket for the build status
[17:47:58.594] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:58.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:52:0) after 1m0s
[17:48:58.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:52:0)
[17:48:58.616] Timer tick, asking Buildbucket for the build status
[17:48:58.656] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:58.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:53:0) after 1m0s
[17:49:58.633] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:53:0)
[17:49:58.633] Timer tick, asking Buildbucket for the build status
[17:49:58.842] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:58.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:54:0) after 1m0s
[17:50:58.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:54:0)
[17:50:58.882] Timer tick, asking Buildbucket for the build status
[17:50:58.929] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:58.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:55:0) after 1m0s
[17:51:58.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:55:0)
[17:51:58.907] Timer tick, asking Buildbucket for the build status
[17:51:59.122] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:59.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:56:0) after 1m0s
[17:52:59.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:56:0)
[17:52:59.149] Timer tick, asking Buildbucket for the build status
[17:52:59.395] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:59.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:57:0) after 1m0s
[17:53:59.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:57:0)
[17:53:59.439] Timer tick, asking Buildbucket for the build status
[17:53:59.502] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:59.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:58:0) after 1m0s
[17:54:59.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:58:0)
[17:54:59.521] Timer tick, asking Buildbucket for the build status
[17:54:59.558] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:59.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:59:0) after 1m0s
[17:55:59.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:59:0)
[17:55:59.548] Timer tick, asking Buildbucket for the build status
[17:55:59.610] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:59.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:60:0) after 1m0s
[17:56:59.626] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:60:0)
[17:56:59.626] Timer tick, asking Buildbucket for the build status
[17:56:59.687] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:59.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:61:0) after 1m0s
[17:57:59.723] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:61:0)
[17:57:59.723] Timer tick, asking Buildbucket for the build status
[17:57:59.764] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:59.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:62:0) after 1m0s
[17:58:59.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:62:0)
[17:58:59.776] Timer tick, asking Buildbucket for the build status
[17:59:00.084] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:00.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:63:0) after 1m0s
[18:00:00.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:63:0)
[18:00:00.068] Timer tick, asking Buildbucket for the build status
[18:00:00.135] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:00.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:64:0) after 1m0s
[18:01:00.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:64:0)
[18:01:00.157] Timer tick, asking Buildbucket for the build status
[18:01:00.256] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:00.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:65:0) after 1m0s
[18:02:00.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:65:0)
[18:02:00.257] Timer tick, asking Buildbucket for the build status
[18:02:00.319] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:00.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:66:0) after 1m0s
[18:03:00.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:66:0)
[18:03:00.382] Timer tick, asking Buildbucket for the build status
[18:03:00.499] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:00.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:67:0) after 1m0s
[18:04:00.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:67:0)
[18:04:00.514] Timer tick, asking Buildbucket for the build status
[18:04:00.683] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:00.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:68:0) after 1m0s
[18:05:00.702] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:68:0)
[18:05:00.702] Timer tick, asking Buildbucket for the build status
[18:05:00.809] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:00.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:69:0) after 1m0s
[18:06:00.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:69:0)
[18:06:00.904] Timer tick, asking Buildbucket for the build status
[18:06:00.972] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:00.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:70:0) after 1m0s
[18:07:00.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:70:0)
[18:07:00.990] Timer tick, asking Buildbucket for the build status
[18:07:01.036] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:01.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:71:0) after 1m0s
[18:08:01.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:71:0)
[18:08:01.077] Timer tick, asking Buildbucket for the build status
[18:08:01.229] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:01.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:72:0) after 1m0s
[18:09:01.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:72:0)
[18:09:01.261] Timer tick, asking Buildbucket for the build status
[18:09:01.306] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:01.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:73:0) after 1m0s
[18:10:01.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:73:0)
[18:10:01.327] Timer tick, asking Buildbucket for the build status
[18:10:01.678] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:01.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:74:0) after 1m0s
[18:11:01.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:74:0)
[18:11:01.665] Timer tick, asking Buildbucket for the build status
[18:11:01.898] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:01.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:75:0) after 1m0s
[18:12:01.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:75:0)
[18:12:01.926] Timer tick, asking Buildbucket for the build status
[18:12:01.978] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:01.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:76:0) after 1m0s
[18:13:01.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:76:0)
[18:13:01.999] Timer tick, asking Buildbucket for the build status
[18:13:03.142] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:03.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:77:0) after 1m0s
[18:14:03.161] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:77:0)
[18:14:03.161] Timer tick, asking Buildbucket for the build status
[18:14:03.279] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:03.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:78:0) after 1m0s
[18:15:03.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:78:0)
[18:15:03.302] Timer tick, asking Buildbucket for the build status
[18:15:03.352] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:03.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:79:0) after 1m0s
[18:16:03.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:79:0)
[18:16:03.383] Timer tick, asking Buildbucket for the build status
[18:16:03.678] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:03.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:80:0) after 1m0s
[18:17:03.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:80:0)
[18:17:03.698] Timer tick, asking Buildbucket for the build status
[18:17:03.964] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:03.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:81:0) after 1m0s
[18:18:03.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:81:0)
[18:18:03.992] Timer tick, asking Buildbucket for the build status
[18:18:04.066] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:04.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:82:0) after 1m0s
[18:19:04.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:82:0)
[18:19:04.097] Timer tick, asking Buildbucket for the build status
[18:19:04.241] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:04.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:83:0) after 1m0s
[18:20:04.263] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:83:0)
[18:20:04.263] Timer tick, asking Buildbucket for the build status
[18:20:04.319] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:04.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:84:0) after 1m0s
[18:21:04.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:84:0)
[18:21:04.302] Timer tick, asking Buildbucket for the build status
[18:21:04.523] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:04.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:85:0) after 1m0s
[18:22:04.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:85:0)
[18:22:04.558] Timer tick, asking Buildbucket for the build status
[18:22:06.394] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:06.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:86:0) after 1m0s
[18:23:06.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:86:0)
[18:23:06.417] Timer tick, asking Buildbucket for the build status
[18:23:06.463] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:06.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:87:0) after 1m0s
[18:24:06.525] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:87:0)
[18:24:06.525] Timer tick, asking Buildbucket for the build status
[18:24:06.684] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:06.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:88:0) after 1m0s
[18:25:06.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:88:0)
[18:25:06.668] Timer tick, asking Buildbucket for the build status
[18:25:06.793] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:06.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:89:0) after 1m0s
[18:26:06.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:89:0)
[18:26:06.826] Timer tick, asking Buildbucket for the build status
[18:26:06.886] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:06.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:90:0) after 1m0s
[18:27:06.872] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:90:0)
[18:27:06.872] Timer tick, asking Buildbucket for the build status
[18:27:06.949] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:06.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:91:0) after 1m0s
[18:28:06.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:91:0)
[18:28:06.973] Timer tick, asking Buildbucket for the build status
[18:28:07.428] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:07.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:92:0) after 1m0s
[18:29:07.450] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:92:0)
[18:29:07.450] Timer tick, asking Buildbucket for the build status
[18:29:07.497] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:07.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:93:0) after 1m0s
[18:30:07.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:93:0)
[18:30:07.517] Timer tick, asking Buildbucket for the build status
[18:30:07.672] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:07.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:94:0) after 1m0s
[18:31:07.699] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:94:0)
[18:31:07.699] Timer tick, asking Buildbucket for the build status
[18:31:07.774] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:07.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:95:0) after 1m0s
[18:32:07.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:95:0)
[18:32:07.796] Timer tick, asking Buildbucket for the build status
[18:32:07.895] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:07.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:96:0) after 1m0s
[18:33:07.924] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:96:0)
[18:33:07.924] Timer tick, asking Buildbucket for the build status
[18:33:08.082] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:08.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:97:0) after 1m0s
[18:34:08.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:97:0)
[18:34:08.090] Timer tick, asking Buildbucket for the build status
[18:34:08.177] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:08.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:98:0) after 1m0s
[18:35:08.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:98:0)
[18:35:08.204] Timer tick, asking Buildbucket for the build status
[18:35:08.267] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:08.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:99:0) after 1m0s
[18:36:08.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:99:0)
[18:36:08.292] Timer tick, asking Buildbucket for the build status
[18:36:08.370] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:08.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:100:0) after 1m0s
[18:37:08.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:100:0)
[18:37:08.401] Timer tick, asking Buildbucket for the build status
[18:37:08.485] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:08.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:101:0) after 1m0s
[18:38:08.505] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:101:0)
[18:38:08.505] Timer tick, asking Buildbucket for the build status
[18:38:08.541] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:08.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:102:0) after 1m0s
[18:39:08.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:102:0)
[18:39:08.571] Timer tick, asking Buildbucket for the build status
[18:39:08.612] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:08.612] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:103:0) after 1m0s
[18:40:08.636] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:103:0)
[18:40:08.636] Timer tick, asking Buildbucket for the build status
[18:40:08.711] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:08.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:104:0) after 1m0s
[18:41:08.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:104:0)
[18:41:08.752] Timer tick, asking Buildbucket for the build status
[18:41:08.836] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:08.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:105:0) after 1m0s
[18:42:08.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:105:0)
[18:42:08.848] Timer tick, asking Buildbucket for the build status
[18:42:08.909] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:08.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:106:0) after 1m0s
[18:43:08.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:106:0)
[18:43:08.933] Timer tick, asking Buildbucket for the build status
[18:43:08.991] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:08.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:107:0) after 1m0s
[18:44:08.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:107:0)
[18:44:08.997] Timer tick, asking Buildbucket for the build status
[18:44:09.035] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:09.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:108:0) after 1m0s
[18:45:09.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:108:0)
[18:45:09.017] Timer tick, asking Buildbucket for the build status
[18:45:09.101] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:09.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:109:0) after 1m0s
[18:46:09.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:109:0)
[18:46:09.152] Timer tick, asking Buildbucket for the build status
[18:46:09.216] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:09.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:110:0) after 1m0s
[18:47:09.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:110:0)
[18:47:09.268] Timer tick, asking Buildbucket for the build status
[18:47:09.331] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:09.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:111:0) after 1m0s
[18:48:09.356] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:111:0)
[18:48:09.356] Timer tick, asking Buildbucket for the build status
[18:48:09.409] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:09.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:112:0) after 1m0s
[18:49:09.396] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:112:0)
[18:49:09.396] Timer tick, asking Buildbucket for the build status
[18:49:09.434] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:09.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:113:0) after 1m0s
[18:50:09.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:113:0)
[18:50:09.457] Timer tick, asking Buildbucket for the build status
[18:50:09.640] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:09.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:114:0) after 1m0s
[18:51:09.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:114:0)
[18:51:09.662] Timer tick, asking Buildbucket for the build status
[18:51:09.986] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:09.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:115:0) after 1m0s
[18:52:10.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:115:0)
[18:52:10.015] Timer tick, asking Buildbucket for the build status
[18:52:10.071] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:10.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:116:0) after 1m0s
[18:53:10.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:116:0)
[18:53:10.072] Timer tick, asking Buildbucket for the build status
[18:53:10.192] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:10.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:117:0) after 1m0s
[18:54:10.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:117:0)
[18:54:10.247] Timer tick, asking Buildbucket for the build status
[18:54:10.294] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:10.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:118:0) after 1m0s
[18:55:10.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:118:0)
[18:55:10.386] Timer tick, asking Buildbucket for the build status
[18:55:10.527] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:10.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:119:0) after 1m0s
[18:56:10.552] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:119:0)
[18:56:10.552] Timer tick, asking Buildbucket for the build status
[18:56:10.620] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:10.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:120:0) after 1m0s
[18:57:10.637] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:120:0)
[18:57:10.637] Timer tick, asking Buildbucket for the build status
[18:57:10.888] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:10.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:121:0) after 1m0s
[18:58:10.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:121:0)
[18:58:10.900] Timer tick, asking Buildbucket for the build status
[18:58:11.065] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:11.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:122:0) after 1m0s
[18:59:11.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:122:0)
[18:59:11.113] Timer tick, asking Buildbucket for the build status
[18:59:11.317] Build 8915187653239138400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:11.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080617715153237504:123:0) after 1m0s
[18:59:19.130] Received PubSub notification, asking Buildbucket for the build status
[18:59:19.169] Build 8915187653239138400: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:59:19.169] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556218758608460",
"created_by": "project:fuchsia",
"created_ts": "1556211531371138",
"failure_reason": "BUILD_FAILURE",
"id": "8915187653239138400",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"ba55767f516af95f8620e95e05e674071a10b6c1\"}}",
"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-ffce87c5-us-central1-b-sflq\", \"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\": \"ba55767f516af95f8620e95e05e674071a10b6c1\", \"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-ffce87c5-us-central1-b-sflq\"], \"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\": [\"4274-a0a5f8c\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1556211535903807",
"status": "COMPLETED",
"status_changed_ts": "1556211536218533",
"tags": [
"builder:clang",
"buildset:commit/git/ba55767f516af95f8620e95e05e674071a10b6c1",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ba55767f516af95f8620e95e05e674071a10b6c1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080617715153237504",
"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/8915187653239138400/+/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:446e410a79b37e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1556218758757555",
"url": "https://ci.chromium.org/b/8915187653239138400",
"utcnow_ts": "1556218759162419"
}
}
[18:59:19.169] Invocation finished in 2h0m29.006369277s with status FAILED