[21:19:30.768] New invocation is queued and will start shortly
[21:19:31.885] Starting the invocation (attempt 1)
[21:19:31.921] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:19:31.921] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:19:31.921] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083409820766743936",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"4dcf3acce6d7455fd079d8e57441906ca2bad254\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083409820766743936",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/4dcf3acce6d7455fd079d8e57441906ca2bad254",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/4dcf3acce6d7455fd079d8e57441906ca2bad254",
"gitiles_ref:refs/heads/master"
]
}
[21:19:33.173] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553548772086969",
"id": "8917979758719018096",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"4dcf3acce6d7455fd079d8e57441906ca2bad254\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553548772848582",
"tags": [
"builder:clang",
"buildset:commit/git/4dcf3acce6d7455fd079d8e57441906ca2bad254",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/4dcf3acce6d7455fd079d8e57441906ca2bad254",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083409820766743936",
"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/8917979758719018096/+/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": "1553548772849323",
"url": "https://ci.chromium.org/b/8917979758719018096",
"utcnow_ts": "1553548773159291"
}
}
[21:19:33.173] Task URL: https://ci.chromium.org/b/8917979758719018096
[21:19:33.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:2:0) after 1m0s
[21:19:34.743] Received PubSub notification, asking Buildbucket for the build status
[21:19:34.807] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:33.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:2:0)
[21:20:33.193] Timer tick, asking Buildbucket for the build status
[21:20:33.397] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:33.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:4:0) after 1m0s
[21:21:33.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:4:0)
[21:21:33.416] Timer tick, asking Buildbucket for the build status
[21:21:33.525] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:33.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:5:0) after 1m0s
[21:22:33.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:5:0)
[21:22:33.540] Timer tick, asking Buildbucket for the build status
[21:22:33.738] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:33.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:6:0) after 1m0s
[21:23:33.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:6:0)
[21:23:33.743] Timer tick, asking Buildbucket for the build status
[21:23:33.821] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:33.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:7:0) after 1m0s
[21:24:33.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:7:0)
[21:24:33.807] Timer tick, asking Buildbucket for the build status
[21:24:33.845] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:33.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:8:0) after 1m0s
[21:25:33.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:8:0)
[21:25:33.864] Timer tick, asking Buildbucket for the build status
[21:25:33.988] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:33.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:9:0) after 1m0s
[21:26:34.024] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:9:0)
[21:26:34.024] Timer tick, asking Buildbucket for the build status
[21:26:34.128] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:34.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:10:0) after 1m0s
[21:27:34.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:10:0)
[21:27:34.149] Timer tick, asking Buildbucket for the build status
[21:27:34.263] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:34.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:11:0) after 1m0s
[21:28:34.262] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:11:0)
[21:28:34.262] Timer tick, asking Buildbucket for the build status
[21:28:34.317] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:34.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:12:0) after 1m0s
[21:29:34.313] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:12:0)
[21:29:34.313] Timer tick, asking Buildbucket for the build status
[21:29:34.491] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:34.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:13:0) after 1m0s
[21:30:34.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:13:0)
[21:30:34.509] Timer tick, asking Buildbucket for the build status
[21:30:34.579] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:34.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:14:0) after 1m0s
[21:31:34.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:14:0)
[21:31:34.592] Timer tick, asking Buildbucket for the build status
[21:31:34.688] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:34.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:15:0) after 1m0s
[21:32:34.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:15:0)
[21:32:34.717] Timer tick, asking Buildbucket for the build status
[21:32:34.759] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:34.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:16:0) after 1m0s
[21:33:34.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:16:0)
[21:33:34.764] Timer tick, asking Buildbucket for the build status
[21:33:34.874] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:34.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:17:0) after 1m0s
[21:34:34.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:17:0)
[21:34:34.848] Timer tick, asking Buildbucket for the build status
[21:34:34.920] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:34.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:18:0) after 1m0s
[21:35:34.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:18:0)
[21:35:34.936] Timer tick, asking Buildbucket for the build status
[21:35:35.006] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:35.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:19:0) after 1m0s
[21:36:35.012] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:19:0)
[21:36:35.012] Timer tick, asking Buildbucket for the build status
[21:36:35.178] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:35.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:20:0) after 1m0s
[21:37:35.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:20:0)
[21:37:35.196] Timer tick, asking Buildbucket for the build status
[21:37:35.247] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:35.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:21:0) after 1m0s
[21:38:35.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:21:0)
[21:38:35.228] Timer tick, asking Buildbucket for the build status
[21:38:35.286] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:35.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:22:0) after 1m0s
[21:39:35.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:22:0)
[21:39:35.323] Timer tick, asking Buildbucket for the build status
[21:39:35.370] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:35.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:23:0) after 1m0s
[21:40:35.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:23:0)
[21:40:35.382] Timer tick, asking Buildbucket for the build status
[21:40:35.429] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:35.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:24:0) after 1m0s
[21:41:35.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:24:0)
[21:41:35.426] Timer tick, asking Buildbucket for the build status
[21:41:35.489] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:35.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:25:0) after 1m0s
[21:42:35.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:25:0)
[21:42:35.515] Timer tick, asking Buildbucket for the build status
[21:42:35.576] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:35.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:26:0) after 1m0s
[21:43:35.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:26:0)
[21:43:35.597] Timer tick, asking Buildbucket for the build status
[21:43:35.757] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:35.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:27:0) after 1m0s
[21:44:35.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:27:0)
[21:44:35.777] Timer tick, asking Buildbucket for the build status
[21:44:35.872] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:35.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:28:0) after 1m0s
[21:45:35.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:28:0)
[21:45:35.900] Timer tick, asking Buildbucket for the build status
[21:45:35.940] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:35.940] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:29:0) after 1m0s
[21:46:35.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:29:0)
[21:46:35.959] Timer tick, asking Buildbucket for the build status
[21:46:36.028] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:36.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:30:0) after 1m0s
[21:47:36.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:30:0)
[21:47:36.053] Timer tick, asking Buildbucket for the build status
[21:47:36.112] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:36.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:31:0) after 1m0s
[21:48:36.132] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:31:0)
[21:48:36.132] Timer tick, asking Buildbucket for the build status
[21:48:36.335] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:36.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:32:0) after 1m0s
[21:49:36.352] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:32:0)
[21:49:36.352] Timer tick, asking Buildbucket for the build status
[21:49:36.547] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:36.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:33:0) after 1m0s
[21:50:36.538] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:33:0)
[21:50:36.538] Timer tick, asking Buildbucket for the build status
[21:50:36.607] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:36.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:34:0) after 1m0s
[21:51:36.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:34:0)
[21:51:36.625] Timer tick, asking Buildbucket for the build status
[21:51:36.751] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:36.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:35:0) after 1m0s
[21:52:36.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:35:0)
[21:52:36.768] Timer tick, asking Buildbucket for the build status
[21:52:36.814] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:36.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:36:0) after 1m0s
[21:53:36.834] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:36:0)
[21:53:36.834] Timer tick, asking Buildbucket for the build status
[21:53:36.940] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:36.940] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:37:0) after 1m0s
[21:54:36.958] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:37:0)
[21:54:36.958] Timer tick, asking Buildbucket for the build status
[21:54:36.997] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:36.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:38:0) after 1m0s
[21:55:37.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:38:0)
[21:55:37.002] Timer tick, asking Buildbucket for the build status
[21:55:37.089] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:37.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:39:0) after 1m0s
[21:56:37.110] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:39:0)
[21:56:37.110] Timer tick, asking Buildbucket for the build status
[21:56:37.163] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:37.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:40:0) after 1m0s
[21:57:37.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:40:0)
[21:57:37.206] Timer tick, asking Buildbucket for the build status
[21:57:37.302] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:37.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:41:0) after 1m0s
[21:58:37.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:41:0)
[21:58:37.273] Timer tick, asking Buildbucket for the build status
[21:58:37.402] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:37.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:42:0) after 1m0s
[21:59:37.419] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:42:0)
[21:59:37.419] Timer tick, asking Buildbucket for the build status
[21:59:37.496] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:37.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:43:0) after 1m0s
[22:00:37.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:43:0)
[22:00:37.537] Timer tick, asking Buildbucket for the build status
[22:00:37.599] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:37.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:44:0) after 1m0s
[22:01:37.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:44:0)
[22:01:37.607] Timer tick, asking Buildbucket for the build status
[22:01:37.642] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:37.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:45:0) after 1m0s
[22:02:37.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:45:0)
[22:02:37.661] Timer tick, asking Buildbucket for the build status
[22:02:37.717] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:37.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:46:0) after 1m0s
[22:03:37.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:46:0)
[22:03:37.703] Timer tick, asking Buildbucket for the build status
[22:03:37.745] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:37.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:47:0) after 1m0s
[22:04:37.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:47:0)
[22:04:37.780] Timer tick, asking Buildbucket for the build status
[22:04:37.850] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:37.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:48:0) after 1m0s
[22:05:37.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:48:0)
[22:05:37.871] Timer tick, asking Buildbucket for the build status
[22:05:37.932] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:37.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:49:0) after 1m0s
[22:06:37.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:49:0)
[22:06:37.951] Timer tick, asking Buildbucket for the build status
[22:06:38.018] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:38.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:50:0) after 1m0s
[22:07:38.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:50:0)
[22:07:38.027] Timer tick, asking Buildbucket for the build status
[22:07:38.089] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:38.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:51:0) after 1m0s
[22:08:38.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:51:0)
[22:08:38.096] Timer tick, asking Buildbucket for the build status
[22:08:38.228] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:38.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:52:0) after 1m0s
[22:09:38.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:52:0)
[22:09:38.264] Timer tick, asking Buildbucket for the build status
[22:09:38.317] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:38.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:53:0) after 1m0s
[22:10:38.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:53:0)
[22:10:38.287] Timer tick, asking Buildbucket for the build status
[22:10:38.406] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:38.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:54:0) after 1m0s
[22:11:38.445] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:54:0)
[22:11:38.445] Timer tick, asking Buildbucket for the build status
[22:11:38.506] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:38.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:55:0) after 1m0s
[22:12:38.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:55:0)
[22:12:38.540] Timer tick, asking Buildbucket for the build status
[22:12:38.592] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:38.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:56:0) after 1m0s
[22:13:38.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:56:0)
[22:13:38.596] Timer tick, asking Buildbucket for the build status
[22:13:38.692] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:38.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:57:0) after 1m0s
[22:14:38.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:57:0)
[22:14:38.700] Timer tick, asking Buildbucket for the build status
[22:14:38.873] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:38.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:58:0) after 1m0s
[22:15:38.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:58:0)
[22:15:38.892] Timer tick, asking Buildbucket for the build status
[22:15:38.943] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:38.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:59:0) after 1m0s
[22:16:38.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:59:0)
[22:16:38.965] Timer tick, asking Buildbucket for the build status
[22:16:39.019] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:39.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:60:0) after 1m0s
[22:17:39.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:60:0)
[22:17:39.008] Timer tick, asking Buildbucket for the build status
[22:17:39.173] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:39.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:61:0) after 1m0s
[22:18:39.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:61:0)
[22:18:39.198] Timer tick, asking Buildbucket for the build status
[22:18:39.238] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:39.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:62:0) after 1m0s
[22:19:39.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:62:0)
[22:19:39.268] Timer tick, asking Buildbucket for the build status
[22:19:39.344] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:39.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:63:0) after 1m0s
[22:20:39.364] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:63:0)
[22:20:39.364] Timer tick, asking Buildbucket for the build status
[22:20:39.471] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:39.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:64:0) after 1m0s
[22:21:39.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:64:0)
[22:21:39.497] Timer tick, asking Buildbucket for the build status
[22:21:39.536] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:39.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:65:0) after 1m0s
[22:22:39.562] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:65:0)
[22:22:39.562] Timer tick, asking Buildbucket for the build status
[22:22:39.681] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:39.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:66:0) after 1m0s
[22:23:39.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:66:0)
[22:23:39.767] Timer tick, asking Buildbucket for the build status
[22:23:39.830] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:39.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:67:0) after 1m0s
[22:24:39.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:67:0)
[22:24:39.849] Timer tick, asking Buildbucket for the build status
[22:24:39.888] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:39.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:68:0) after 1m0s
[22:25:39.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:68:0)
[22:25:39.877] Timer tick, asking Buildbucket for the build status
[22:25:40.214] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:40.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:69:0) after 1m0s
[22:26:40.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:69:0)
[22:26:40.204] Timer tick, asking Buildbucket for the build status
[22:26:40.268] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:40.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:70:0) after 1m0s
[22:27:40.290] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:70:0)
[22:27:40.290] Timer tick, asking Buildbucket for the build status
[22:27:40.688] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:40.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:71:0) after 1m0s
[22:28:40.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:71:0)
[22:28:40.710] Timer tick, asking Buildbucket for the build status
[22:28:40.749] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:40.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:72:0) after 1m0s
[22:29:40.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:72:0)
[22:29:40.773] Timer tick, asking Buildbucket for the build status
[22:29:40.828] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:40.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:73:0) after 1m0s
[22:30:40.817] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:73:0)
[22:30:40.817] Timer tick, asking Buildbucket for the build status
[22:30:40.904] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:40.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:74:0) after 1m0s
[22:31:40.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:74:0)
[22:31:40.923] Timer tick, asking Buildbucket for the build status
[22:31:41.021] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:41.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:75:0) after 1m0s
[22:32:41.045] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:75:0)
[22:32:41.045] Timer tick, asking Buildbucket for the build status
[22:32:41.233] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:41.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:76:0) after 1m0s
[22:33:41.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:76:0)
[22:33:41.245] Timer tick, asking Buildbucket for the build status
[22:33:41.395] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:41.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:77:0) after 1m0s
[22:34:41.418] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:77:0)
[22:34:41.418] Timer tick, asking Buildbucket for the build status
[22:34:41.491] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:41.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:78:0) after 1m0s
[22:35:41.473] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:78:0)
[22:35:41.473] Timer tick, asking Buildbucket for the build status
[22:35:41.591] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:41.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:79:0) after 1m0s
[22:36:41.645] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:79:0)
[22:36:41.645] Timer tick, asking Buildbucket for the build status
[22:36:41.704] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:41.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:80:0) after 1m0s
[22:37:41.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:80:0)
[22:37:41.726] Timer tick, asking Buildbucket for the build status
[22:37:41.810] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:41.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:81:0) after 1m0s
[22:38:41.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:81:0)
[22:38:41.865] Timer tick, asking Buildbucket for the build status
[22:38:41.915] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:41.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:82:0) after 1m0s
[22:39:41.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:82:0)
[22:39:41.902] Timer tick, asking Buildbucket for the build status
[22:39:41.951] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:41.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:83:0) after 1m0s
[22:40:41.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:83:0)
[22:40:41.965] Timer tick, asking Buildbucket for the build status
[22:40:42.058] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:42.058] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:84:0) after 1m0s
[22:41:42.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:84:0)
[22:41:42.109] Timer tick, asking Buildbucket for the build status
[22:41:42.160] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:42.160] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:85:0) after 1m0s
[22:42:42.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:85:0)
[22:42:42.181] Timer tick, asking Buildbucket for the build status
[22:42:42.258] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:42.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:86:0) after 1m0s
[22:43:42.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:86:0)
[22:43:42.249] Timer tick, asking Buildbucket for the build status
[22:43:42.362] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:42.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:87:0) after 1m0s
[22:44:42.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:87:0)
[22:44:42.406] Timer tick, asking Buildbucket for the build status
[22:44:42.496] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:42.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:88:0) after 1m0s
[22:45:42.527] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:88:0)
[22:45:42.527] Timer tick, asking Buildbucket for the build status
[22:45:42.679] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:42.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:89:0) after 1m0s
[22:46:42.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:89:0)
[22:46:42.673] Timer tick, asking Buildbucket for the build status
[22:46:42.738] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:42.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:90:0) after 1m0s
[22:47:42.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:90:0)
[22:47:42.757] Timer tick, asking Buildbucket for the build status
[22:47:43.041] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:43.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:91:0) after 1m0s
[22:48:43.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:91:0)
[22:48:43.073] Timer tick, asking Buildbucket for the build status
[22:48:43.142] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:43.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:92:0) after 1m0s
[22:49:43.120] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:92:0)
[22:49:43.120] Timer tick, asking Buildbucket for the build status
[22:49:43.166] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:43.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:93:0) after 1m0s
[22:50:43.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:93:0)
[22:50:43.194] Timer tick, asking Buildbucket for the build status
[22:50:43.295] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:43.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:94:0) after 1m0s
[22:51:43.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:94:0)
[22:51:43.338] Timer tick, asking Buildbucket for the build status
[22:51:43.381] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:43.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:95:0) after 1m0s
[22:52:43.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:95:0)
[22:52:43.404] Timer tick, asking Buildbucket for the build status
[22:52:43.481] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:43.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:96:0) after 1m0s
[22:53:43.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:96:0)
[22:53:43.518] Timer tick, asking Buildbucket for the build status
[22:53:43.564] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:43.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:97:0) after 1m0s
[22:54:43.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:97:0)
[22:54:43.618] Timer tick, asking Buildbucket for the build status
[22:54:43.699] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:43.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:98:0) after 1m0s
[22:55:43.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:98:0)
[22:55:43.720] Timer tick, asking Buildbucket for the build status
[22:55:43.770] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:43.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:99:0) after 1m0s
[22:56:43.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:99:0)
[22:56:43.803] Timer tick, asking Buildbucket for the build status
[22:56:43.928] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:43.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:100:0) after 1m0s
[22:57:43.944] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:100:0)
[22:57:43.944] Timer tick, asking Buildbucket for the build status
[22:57:43.991] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:43.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:101:0) after 1m0s
[22:58:44.038] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:101:0)
[22:58:44.038] Timer tick, asking Buildbucket for the build status
[22:58:44.172] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:44.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:102:0) after 1m0s
[22:59:44.158] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:102:0)
[22:59:44.158] Timer tick, asking Buildbucket for the build status
[22:59:44.209] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:44.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:103:0) after 1m0s
[23:00:44.229] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:103:0)
[23:00:44.229] Timer tick, asking Buildbucket for the build status
[23:00:44.270] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:44.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:104:0) after 1m0s
[23:01:44.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:104:0)
[23:01:44.265] Timer tick, asking Buildbucket for the build status
[23:01:44.376] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:44.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:105:0) after 1m0s
[23:02:44.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:105:0)
[23:02:44.432] Timer tick, asking Buildbucket for the build status
[23:02:44.501] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:44.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:106:0) after 1m0s
[23:03:44.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:106:0)
[23:03:44.523] Timer tick, asking Buildbucket for the build status
[23:03:44.570] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:44.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:107:0) after 1m0s
[23:04:44.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:107:0)
[23:04:44.596] Timer tick, asking Buildbucket for the build status
[23:04:44.747] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:44.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:108:0) after 1m0s
[23:05:44.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:108:0)
[23:05:44.742] Timer tick, asking Buildbucket for the build status
[23:05:44.822] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:44.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:109:0) after 1m0s
[23:06:44.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:109:0)
[23:06:44.887] Timer tick, asking Buildbucket for the build status
[23:06:45.194] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:45.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:110:0) after 1m0s
[23:07:45.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:110:0)
[23:07:45.191] Timer tick, asking Buildbucket for the build status
[23:07:45.238] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:45.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:111:0) after 1m0s
[23:08:45.229] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:111:0)
[23:08:45.229] Timer tick, asking Buildbucket for the build status
[23:08:45.284] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:45.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:112:0) after 1m0s
[23:09:45.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:112:0)
[23:09:45.308] Timer tick, asking Buildbucket for the build status
[23:09:45.467] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:45.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:113:0) after 1m0s
[23:10:45.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:113:0)
[23:10:45.488] Timer tick, asking Buildbucket for the build status
[23:10:45.589] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:45.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:114:0) after 1m0s
[23:11:45.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:114:0)
[23:11:45.572] Timer tick, asking Buildbucket for the build status
[23:11:45.763] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:45.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:115:0) after 1m0s
[23:12:45.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:115:0)
[23:12:45.783] Timer tick, asking Buildbucket for the build status
[23:12:45.981] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:45.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:116:0) after 1m0s
[23:13:46.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:116:0)
[23:13:46.004] Timer tick, asking Buildbucket for the build status
[23:13:46.159] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:46.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:117:0) after 1m0s
[23:14:46.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:117:0)
[23:14:46.185] Timer tick, asking Buildbucket for the build status
[23:14:46.376] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:46.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:118:0) after 1m0s
[23:15:46.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:118:0)
[23:15:46.390] Timer tick, asking Buildbucket for the build status
[23:15:46.470] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:46.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:119:0) after 1m0s
[23:16:46.495] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:119:0)
[23:16:46.495] Timer tick, asking Buildbucket for the build status
[23:16:47.132] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:47.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:120:0) after 1m0s
[23:17:47.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:120:0)
[23:17:47.127] Timer tick, asking Buildbucket for the build status
[23:17:47.174] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:47.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:121:0) after 1m0s
[23:18:47.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:121:0)
[23:18:47.194] Timer tick, asking Buildbucket for the build status
[23:18:47.265] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:47.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:122:0) after 1m0s
[23:19:47.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:122:0)
[23:19:47.274] Timer tick, asking Buildbucket for the build status
[23:19:47.387] Build 8917979758719018096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:47.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083409820766743936:123:0) after 1m0s
[23:20:00.326] Received PubSub notification, asking Buildbucket for the build status
[23:20:00.367] Build 8917979758719018096: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:20:00.367] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553555999965690",
"created_by": "project:fuchsia",
"created_ts": "1553548772086969",
"failure_reason": "BUILD_FAILURE",
"id": "8917979758719018096",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"4dcf3acce6d7455fd079d8e57441906ca2bad254\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-7887e815-us-central1-b-w919\", \"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\": \"4dcf3acce6d7455fd079d8e57441906ca2bad254\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-7887e815-us-central1-b-w919\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4216-cf4a3cd\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-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": "1553548773797319",
"status": "COMPLETED",
"status_changed_ts": "1553548774465625",
"tags": [
"builder:clang",
"buildset:commit/git/4dcf3acce6d7455fd079d8e57441906ca2bad254",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/4dcf3acce6d7455fd079d8e57441906ca2bad254",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083409820766743936",
"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/8917979758719018096/+/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:43cf8a8f452f1b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553556000027034",
"url": "https://ci.chromium.org/b/8917979758719018096",
"utcnow_ts": "1553556000359692"
}
}
[23:20:00.367] Invocation finished in 2h0m29.616871101s with status FAILED