[21:25:32.171] New invocation is queued and will start shortly
[21:25:33.290] Starting the invocation (attempt 1)
[21:25:33.337] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:25:33.337] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:25:33.338] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9085130784171633296",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"982cbb62325179ac31478260619e309e23d35711\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9085130784171633296",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/982cbb62325179ac31478260619e309e23d35711",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/982cbb62325179ac31478260619e309e23d35711",
"gitiles_ref:refs/heads/master"
]
}
[21:25:34.761] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551907533479041",
"id": "8919700722133227760",
"lease_expiration_ts": "1552033534328992",
"lease_key": "834118032",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"982cbb62325179ac31478260619e309e23d35711\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551907534554573",
"tags": [
"builder:clang",
"buildset:commit/git/982cbb62325179ac31478260619e309e23d35711",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/982cbb62325179ac31478260619e309e23d35711",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085130784171633296",
"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/8919700722133227760/+/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:436db73cf32e3510",
"user_agent:luci-scheduler"
],
"updated_ts": "1551907534554687",
"url": "https://ci.chromium.org/b/8919700722133227760",
"utcnow_ts": "1551907534750369"
}
}
[21:25:34.761] Task URL: https://ci.chromium.org/b/8919700722133227760
[21:25:34.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:2:0) after 1m0s
[21:25:37.013] Received PubSub notification, asking Buildbucket for the build status
[21:25:37.088] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:34.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:2:0)
[21:26:34.732] Timer tick, asking Buildbucket for the build status
[21:26:34.794] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:34.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:4:0) after 1m0s
[21:27:34.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:4:0)
[21:27:34.806] Timer tick, asking Buildbucket for the build status
[21:27:34.890] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:34.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:5:0) after 1m0s
[21:28:34.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:5:0)
[21:28:34.908] Timer tick, asking Buildbucket for the build status
[21:28:34.973] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:34.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:6:0) after 1m0s
[21:29:35.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:6:0)
[21:29:35.084] Timer tick, asking Buildbucket for the build status
[21:29:35.256] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:35.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:7:0) after 1m0s
[21:30:35.240] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:7:0)
[21:30:35.240] Timer tick, asking Buildbucket for the build status
[21:30:35.289] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:35.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:8:0) after 1m0s
[21:31:35.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:8:0)
[21:31:35.318] Timer tick, asking Buildbucket for the build status
[21:31:35.359] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:35.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:9:0) after 1m0s
[21:32:35.376] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:9:0)
[21:32:35.376] Timer tick, asking Buildbucket for the build status
[21:32:35.471] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:35.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:10:0) after 1m0s
[21:33:35.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:10:0)
[21:33:35.443] Timer tick, asking Buildbucket for the build status
[21:33:35.507] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:35.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:11:0) after 1m0s
[21:34:35.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:11:0)
[21:34:35.507] Timer tick, asking Buildbucket for the build status
[21:34:35.623] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:35.623] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:12:0) after 1m0s
[21:35:35.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:12:0)
[21:35:35.641] Timer tick, asking Buildbucket for the build status
[21:35:35.695] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:35.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:13:0) after 1m0s
[21:36:35.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:13:0)
[21:36:35.696] Timer tick, asking Buildbucket for the build status
[21:36:35.744] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:35.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:14:0) after 1m0s
[21:37:35.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:14:0)
[21:37:35.715] Timer tick, asking Buildbucket for the build status
[21:37:35.758] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:35.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:15:0) after 1m0s
[21:38:35.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:15:0)
[21:38:35.786] Timer tick, asking Buildbucket for the build status
[21:38:36.196] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:36.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:16:0) after 1m0s
[21:39:36.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:16:0)
[21:39:36.224] Timer tick, asking Buildbucket for the build status
[21:39:36.267] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:36.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:17:0) after 1m0s
[21:40:36.279] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:17:0)
[21:40:36.279] Timer tick, asking Buildbucket for the build status
[21:40:36.333] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:36.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:18:0) after 1m0s
[21:41:36.352] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:18:0)
[21:41:36.352] Timer tick, asking Buildbucket for the build status
[21:41:36.419] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:36.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:19:0) after 1m0s
[21:42:36.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:19:0)
[21:42:36.414] Timer tick, asking Buildbucket for the build status
[21:42:36.492] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:36.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:20:0) after 1m0s
[21:43:36.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:20:0)
[21:43:36.511] Timer tick, asking Buildbucket for the build status
[21:43:36.583] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:36.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:21:0) after 1m0s
[21:44:36.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:21:0)
[21:44:36.603] Timer tick, asking Buildbucket for the build status
[21:44:36.711] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:36.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:22:0) after 1m0s
[21:45:36.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:22:0)
[21:45:36.700] Timer tick, asking Buildbucket for the build status
[21:45:36.749] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:36.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:23:0) after 1m0s
[21:46:36.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:23:0)
[21:46:36.753] Timer tick, asking Buildbucket for the build status
[21:46:36.795] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:36.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:24:0) after 1m0s
[21:47:36.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:24:0)
[21:47:36.764] Timer tick, asking Buildbucket for the build status
[21:47:36.890] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:36.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:25:0) after 1m0s
[21:48:36.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:25:0)
[21:48:36.879] Timer tick, asking Buildbucket for the build status
[21:48:36.984] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:36.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:26:0) after 1m0s
[21:49:37.066] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:26:0)
[21:49:37.066] Timer tick, asking Buildbucket for the build status
[21:49:37.128] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:37.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:27:0) after 1m0s
[21:50:37.151] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:27:0)
[21:50:37.151] Timer tick, asking Buildbucket for the build status
[21:50:37.203] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:37.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:28:0) after 1m0s
[21:51:37.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:28:0)
[21:51:37.209] Timer tick, asking Buildbucket for the build status
[21:51:37.340] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:37.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:29:0) after 1m0s
[21:52:37.337] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:29:0)
[21:52:37.337] Timer tick, asking Buildbucket for the build status
[21:52:37.548] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:37.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:30:0) after 1m0s
[21:53:37.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:30:0)
[21:53:37.568] Timer tick, asking Buildbucket for the build status
[21:53:37.622] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:37.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:31:0) after 1m0s
[21:54:37.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:31:0)
[21:54:37.623] Timer tick, asking Buildbucket for the build status
[21:54:37.664] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:37.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:32:0) after 1m0s
[21:55:37.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:32:0)
[21:55:37.665] Timer tick, asking Buildbucket for the build status
[21:55:37.774] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:37.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:33:0) after 1m0s
[21:56:37.811] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:33:0)
[21:56:37.811] Timer tick, asking Buildbucket for the build status
[21:56:37.910] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:37.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:34:0) after 1m0s
[21:57:37.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:34:0)
[21:57:37.942] Timer tick, asking Buildbucket for the build status
[21:57:37.993] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:37.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:35:0) after 1m0s
[21:58:37.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:35:0)
[21:58:37.987] Timer tick, asking Buildbucket for the build status
[21:58:38.064] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:38.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:36:0) after 1m0s
[21:59:38.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:36:0)
[21:59:38.093] Timer tick, asking Buildbucket for the build status
[21:59:38.212] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:38.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:37:0) after 1m0s
[22:00:38.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:37:0)
[22:00:38.287] Timer tick, asking Buildbucket for the build status
[22:00:38.395] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:38.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:38:0) after 1m0s
[22:01:38.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:38:0)
[22:01:38.426] Timer tick, asking Buildbucket for the build status
[22:01:38.593] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:38.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:39:0) after 1m0s
[22:02:38.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:39:0)
[22:02:38.612] Timer tick, asking Buildbucket for the build status
[22:02:38.709] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:38.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:40:0) after 1m0s
[22:03:38.750] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:40:0)
[22:03:38.750] Timer tick, asking Buildbucket for the build status
[22:03:38.802] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:38.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:41:0) after 1m0s
[22:04:38.817] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:41:0)
[22:04:38.817] Timer tick, asking Buildbucket for the build status
[22:04:38.871] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:38.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:42:0) after 1m0s
[22:05:38.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:42:0)
[22:05:38.897] Timer tick, asking Buildbucket for the build status
[22:05:38.941] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:38.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:43:0) after 1m0s
[22:06:38.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:43:0)
[22:06:38.959] Timer tick, asking Buildbucket for the build status
[22:06:39.013] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:39.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:44:0) after 1m0s
[22:07:39.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:44:0)
[22:07:39.036] Timer tick, asking Buildbucket for the build status
[22:07:39.116] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:39.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:45:0) after 1m0s
[22:08:39.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:45:0)
[22:08:39.099] Timer tick, asking Buildbucket for the build status
[22:08:39.162] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:39.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:46:0) after 1m0s
[22:09:39.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:46:0)
[22:09:39.193] Timer tick, asking Buildbucket for the build status
[22:09:39.274] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:39.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:47:0) after 1m0s
[22:10:39.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:47:0)
[22:10:39.292] Timer tick, asking Buildbucket for the build status
[22:10:39.396] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:39.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:48:0) after 1m0s
[22:11:39.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:48:0)
[22:11:39.387] Timer tick, asking Buildbucket for the build status
[22:11:39.434] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:39.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:49:0) after 1m0s
[22:12:39.424] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:49:0)
[22:12:39.424] Timer tick, asking Buildbucket for the build status
[22:12:39.550] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:39.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:50:0) after 1m0s
[22:13:39.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:50:0)
[22:13:39.563] Timer tick, asking Buildbucket for the build status
[22:13:39.706] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:39.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:51:0) after 1m0s
[22:14:39.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:51:0)
[22:14:39.707] Timer tick, asking Buildbucket for the build status
[22:14:39.759] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:39.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:52:0) after 1m0s
[22:15:39.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:52:0)
[22:15:39.752] Timer tick, asking Buildbucket for the build status
[22:15:39.913] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:39.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:53:0) after 1m0s
[22:16:39.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:53:0)
[22:16:39.942] Timer tick, asking Buildbucket for the build status
[22:16:39.995] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:39.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:54:0) after 1m0s
[22:17:39.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:54:0)
[22:17:39.965] Timer tick, asking Buildbucket for the build status
[22:17:40.078] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:40.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:55:0) after 1m0s
[22:18:40.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:55:0)
[22:18:40.091] Timer tick, asking Buildbucket for the build status
[22:18:40.140] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:40.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:56:0) after 1m0s
[22:19:40.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:56:0)
[22:19:40.138] Timer tick, asking Buildbucket for the build status
[22:19:40.202] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:40.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:57:0) after 1m0s
[22:20:40.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:57:0)
[22:20:40.224] Timer tick, asking Buildbucket for the build status
[22:20:40.515] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:40.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:58:0) after 1m0s
[22:21:40.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:58:0)
[22:21:40.492] Timer tick, asking Buildbucket for the build status
[22:21:40.552] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:40.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:59:0) after 1m0s
[22:22:40.637] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:59:0)
[22:22:40.637] Timer tick, asking Buildbucket for the build status
[22:22:40.709] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:40.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:60:0) after 1m0s
[22:23:40.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:60:0)
[22:23:40.732] Timer tick, asking Buildbucket for the build status
[22:23:40.777] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:40.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:61:0) after 1m0s
[22:24:40.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:61:0)
[22:24:40.820] Timer tick, asking Buildbucket for the build status
[22:24:40.876] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:40.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:62:0) after 1m0s
[22:25:40.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:62:0)
[22:25:40.902] Timer tick, asking Buildbucket for the build status
[22:25:40.945] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:40.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:63:0) after 1m0s
[22:26:40.960] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:63:0)
[22:26:40.960] Timer tick, asking Buildbucket for the build status
[22:26:41.112] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:41.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:64:0) after 1m0s
[22:27:41.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:64:0)
[22:27:41.128] Timer tick, asking Buildbucket for the build status
[22:27:41.180] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:41.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:65:0) after 1m0s
[22:28:41.150] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:65:0)
[22:28:41.150] Timer tick, asking Buildbucket for the build status
[22:28:41.198] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:41.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:66:0) after 1m0s
[22:29:41.195] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:66:0)
[22:29:41.195] Timer tick, asking Buildbucket for the build status
[22:29:41.291] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:41.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:67:0) after 1m0s
[22:30:41.284] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:67:0)
[22:30:41.284] Timer tick, asking Buildbucket for the build status
[22:30:41.408] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:41.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:68:0) after 1m0s
[22:31:41.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:68:0)
[22:31:41.430] Timer tick, asking Buildbucket for the build status
[22:31:41.557] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:41.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:69:0) after 1m0s
[22:32:41.575] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:69:0)
[22:32:41.576] Timer tick, asking Buildbucket for the build status
[22:32:41.620] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:41.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:70:0) after 1m0s
[22:33:41.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:70:0)
[22:33:41.615] Timer tick, asking Buildbucket for the build status
[22:33:41.662] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:41.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:71:0) after 1m0s
[22:34:41.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:71:0)
[22:34:41.672] Timer tick, asking Buildbucket for the build status
[22:34:41.721] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:41.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:72:0) after 1m0s
[22:35:41.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:72:0)
[22:35:41.709] Timer tick, asking Buildbucket for the build status
[22:35:41.775] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:41.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:73:0) after 1m0s
[22:36:41.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:73:0)
[22:36:41.783] Timer tick, asking Buildbucket for the build status
[22:36:41.831] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:41.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:74:0) after 1m0s
[22:37:41.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:74:0)
[22:37:41.808] Timer tick, asking Buildbucket for the build status
[22:37:41.910] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:41.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:75:0) after 1m0s
[22:38:41.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:75:0)
[22:38:41.920] Timer tick, asking Buildbucket for the build status
[22:38:41.982] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:41.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:76:0) after 1m0s
[22:39:41.952] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:76:0)
[22:39:41.952] Timer tick, asking Buildbucket for the build status
[22:39:41.998] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:41.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:77:0) after 1m0s
[22:40:42.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:77:0)
[22:40:42.034] Timer tick, asking Buildbucket for the build status
[22:40:42.129] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:42.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:78:0) after 1m0s
[22:41:42.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:78:0)
[22:41:42.224] Timer tick, asking Buildbucket for the build status
[22:41:42.269] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:42.269] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:79:0) after 1m0s
[22:42:42.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:79:0)
[22:42:42.332] Timer tick, asking Buildbucket for the build status
[22:42:42.380] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:42.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:80:0) after 1m0s
[22:43:42.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:80:0)
[22:43:42.398] Timer tick, asking Buildbucket for the build status
[22:43:42.451] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:42.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:81:0) after 1m0s
[22:44:42.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:81:0)
[22:44:42.470] Timer tick, asking Buildbucket for the build status
[22:44:42.517] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:42.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:82:0) after 1m0s
[22:45:42.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:82:0)
[22:45:42.537] Timer tick, asking Buildbucket for the build status
[22:45:42.837] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:42.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:83:0) after 1m0s
[22:46:42.961] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:83:0)
[22:46:42.961] Timer tick, asking Buildbucket for the build status
[22:46:43.006] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:43.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:84:0) after 1m0s
[22:47:42.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:84:0)
[22:47:42.980] Timer tick, asking Buildbucket for the build status
[22:47:43.031] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:43.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:85:0) after 1m0s
[22:48:43.062] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:85:0)
[22:48:43.062] Timer tick, asking Buildbucket for the build status
[22:48:43.227] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:43.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:86:0) after 1m0s
[22:49:43.232] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:86:0)
[22:49:43.232] Timer tick, asking Buildbucket for the build status
[22:49:43.547] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:43.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:87:0) after 1m0s
[22:50:43.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:87:0)
[22:50:43.571] Timer tick, asking Buildbucket for the build status
[22:50:43.790] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:43.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:88:0) after 1m0s
[22:51:43.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:88:0)
[22:51:43.823] Timer tick, asking Buildbucket for the build status
[22:51:43.924] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:43.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:89:0) after 1m0s
[22:52:43.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:89:0)
[22:52:43.906] Timer tick, asking Buildbucket for the build status
[22:52:43.968] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:43.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:90:0) after 1m0s
[22:53:43.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:90:0)
[22:53:43.968] Timer tick, asking Buildbucket for the build status
[22:53:44.017] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:44.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:91:0) after 1m0s
[22:54:44.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:91:0)
[22:54:44.015] Timer tick, asking Buildbucket for the build status
[22:54:44.066] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:44.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:92:0) after 1m0s
[22:55:44.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:92:0)
[22:55:44.089] Timer tick, asking Buildbucket for the build status
[22:55:44.480] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:44.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:93:0) after 1m0s
[22:56:44.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:93:0)
[22:56:44.483] Timer tick, asking Buildbucket for the build status
[22:56:44.847] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:44.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:94:0) after 1m0s
[22:57:44.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:94:0)
[22:57:44.867] Timer tick, asking Buildbucket for the build status
[22:57:45.072] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:45.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:95:0) after 1m0s
[22:58:45.046] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:95:0)
[22:58:45.046] Timer tick, asking Buildbucket for the build status
[22:58:45.090] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:45.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:96:0) after 1m0s
[22:59:45.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:96:0)
[22:59:45.109] Timer tick, asking Buildbucket for the build status
[22:59:45.158] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:45.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:97:0) after 1m0s
[23:00:45.176] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:97:0)
[23:00:45.176] Timer tick, asking Buildbucket for the build status
[23:00:45.268] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:45.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:98:0) after 1m0s
[23:01:45.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:98:0)
[23:01:45.276] Timer tick, asking Buildbucket for the build status
[23:01:45.336] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:45.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:99:0) after 1m0s
[23:02:45.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:99:0)
[23:02:45.355] Timer tick, asking Buildbucket for the build status
[23:02:45.404] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:45.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:100:0) after 1m0s
[23:03:45.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:100:0)
[23:03:45.429] Timer tick, asking Buildbucket for the build status
[23:03:45.477] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:45.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:101:0) after 1m0s
[23:04:45.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:101:0)
[23:04:45.457] Timer tick, asking Buildbucket for the build status
[23:04:45.520] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:45.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:102:0) after 1m0s
[23:05:45.554] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:102:0)
[23:05:45.554] Timer tick, asking Buildbucket for the build status
[23:05:45.593] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:45.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:103:0) after 1m0s
[23:06:45.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:103:0)
[23:06:45.628] Timer tick, asking Buildbucket for the build status
[23:06:45.688] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:45.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:104:0) after 1m0s
[23:07:45.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:104:0)
[23:07:45.709] Timer tick, asking Buildbucket for the build status
[23:07:45.756] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:45.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:105:0) after 1m0s
[23:08:45.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:105:0)
[23:08:45.749] Timer tick, asking Buildbucket for the build status
[23:08:45.810] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:45.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:106:0) after 1m0s
[23:09:45.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:106:0)
[23:09:45.864] Timer tick, asking Buildbucket for the build status
[23:09:46.009] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:46.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:107:0) after 1m0s
[23:10:46.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:107:0)
[23:10:46.015] Timer tick, asking Buildbucket for the build status
[23:10:46.333] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:46.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:108:0) after 1m0s
[23:11:46.350] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:108:0)
[23:11:46.350] Timer tick, asking Buildbucket for the build status
[23:11:46.487] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:46.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:109:0) after 1m0s
[23:12:46.527] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:109:0)
[23:12:46.527] Timer tick, asking Buildbucket for the build status
[23:12:46.685] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:46.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:110:0) after 1m0s
[23:13:46.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:110:0)
[23:13:46.678] Timer tick, asking Buildbucket for the build status
[23:13:46.741] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:46.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:111:0) after 1m0s
[23:14:46.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:111:0)
[23:14:46.784] Timer tick, asking Buildbucket for the build status
[23:14:46.843] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:46.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:112:0) after 1m0s
[23:15:46.863] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:112:0)
[23:15:46.863] Timer tick, asking Buildbucket for the build status
[23:15:46.988] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:46.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:113:0) after 1m0s
[23:16:47.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:113:0)
[23:16:47.006] Timer tick, asking Buildbucket for the build status
[23:16:47.188] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:47.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:114:0) after 1m0s
[23:17:47.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:114:0)
[23:17:47.169] Timer tick, asking Buildbucket for the build status
[23:17:47.226] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:47.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:115:0) after 1m0s
[23:18:47.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:115:0)
[23:18:47.210] Timer tick, asking Buildbucket for the build status
[23:18:47.255] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:47.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:116:0) after 1m0s
[23:19:47.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:116:0)
[23:19:47.268] Timer tick, asking Buildbucket for the build status
[23:19:47.333] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:47.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:117:0) after 1m0s
[23:20:47.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:117:0)
[23:20:47.347] Timer tick, asking Buildbucket for the build status
[23:20:47.432] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:47.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:118:0) after 1m0s
[23:21:47.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:118:0)
[23:21:47.420] Timer tick, asking Buildbucket for the build status
[23:21:47.482] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:47.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:119:0) after 1m0s
[23:22:47.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:119:0)
[23:22:47.506] Timer tick, asking Buildbucket for the build status
[23:22:47.597] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:47.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:120:0) after 1m0s
[23:23:47.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:120:0)
[23:23:47.608] Timer tick, asking Buildbucket for the build status
[23:23:47.667] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:47.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:121:0) after 1m0s
[23:24:47.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:121:0)
[23:24:47.673] Timer tick, asking Buildbucket for the build status
[23:24:47.739] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:47.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:122:0) after 1m0s
[23:25:47.759] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:122:0)
[23:25:47.759] Timer tick, asking Buildbucket for the build status
[23:25:47.800] Build 8919700722133227760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:47.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085130784171633296:123:0) after 1m0s
[23:26:14.123] Received PubSub notification, asking Buildbucket for the build status
[23:26:14.164] Build 8919700722133227760: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:26:14.164] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1551914773111219",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551907533479041",
"failure_reason": "BUILD_FAILURE",
"id": "8919700722133227760",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"982cbb62325179ac31478260619e309e23d35711\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"image\": [\"fuchsia-debian-9-drawfork-v20181107-ee01d6c\"], \"kvm\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4152-09944d7\"], \"machine_type\": [\"n1-standard-1\"], \"gpu\": [\"none\"], \"cores\": [\"1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"id\": [\"fuchsia-debian-1-03c1d9de-us-central1-c-kg11\"], \"pool\": [\"luci.fuchsia.prod\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"Failure buildbucket.run\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"recipe\": \"buildbucket_trigger\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"timeout\": 7200.0, \"path_config\": \"generic\", \"bot_id\": \"fuchsia-debian-1-03c1d9de-us-central1-c-kg11\", \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"revision\": \"982cbb62325179ac31478260619e309e23d35711\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1551907535663422",
"status": "COMPLETED",
"status_changed_ts": "1551914773677283",
"tags": [
"builder:clang",
"buildset:commit/git/982cbb62325179ac31478260619e309e23d35711",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/982cbb62325179ac31478260619e309e23d35711",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085130784171633296",
"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/8919700722133227760/+/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:436db73cf32e3510",
"user_agent:luci-scheduler"
],
"updated_ts": "1551914773679212",
"url": "https://ci.chromium.org/b/8919700722133227760",
"utcnow_ts": "1551914774155911"
}
}
[23:26:14.164] Invocation finished in 2h0m42.013010128s with status FAILED