[08:47:56.502] New invocation is queued and will start shortly
[08:47:57.688] Starting the invocation (attempt 1)
[08:47:57.733] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:47:57.733] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:47:57.733] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083004120699158864",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"d880de2d19d46f7cfea7aa593602458440bc1e8d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083004120699158864",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/d880de2d19d46f7cfea7aa593602458440bc1e8d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/d880de2d19d46f7cfea7aa593602458440bc1e8d",
"gitiles_ref:refs/heads/master"
]
}
[08:47:58.252] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553935677806960",
"id": "8917574058667117392",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"d880de2d19d46f7cfea7aa593602458440bc1e8d\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553935678081291",
"tags": [
"builder:clang",
"buildset:commit/git/d880de2d19d46f7cfea7aa593602458440bc1e8d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/d880de2d19d46f7cfea7aa593602458440bc1e8d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083004120699158864",
"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/8917574058667117392/+/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": "1553935678081532",
"url": "https://ci.chromium.org/b/8917574058667117392",
"utcnow_ts": "1553935678239412"
}
}
[08:47:58.252] Task URL: https://ci.chromium.org/b/8917574058667117392
[08:47:58.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:2:0) after 1m0s
[08:48:21.663] Received PubSub notification, asking Buildbucket for the build status
[08:48:21.732] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:58.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:2:0)
[08:48:58.271] Timer tick, asking Buildbucket for the build status
[08:48:58.433] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:58.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:4:0) after 1m0s
[08:49:58.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:4:0)
[08:49:58.453] Timer tick, asking Buildbucket for the build status
[08:49:58.530] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:58.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:5:0) after 1m0s
[08:50:58.549] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:5:0)
[08:50:58.550] Timer tick, asking Buildbucket for the build status
[08:50:58.599] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:58.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:6:0) after 1m0s
[08:51:58.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:6:0)
[08:51:58.610] Timer tick, asking Buildbucket for the build status
[08:51:58.674] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:58.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:7:0) after 1m0s
[08:52:58.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:7:0)
[08:52:58.695] Timer tick, asking Buildbucket for the build status
[08:52:58.776] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:58.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:8:0) after 1m0s
[08:53:58.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:8:0)
[08:53:58.774] Timer tick, asking Buildbucket for the build status
[08:53:58.852] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:58.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:9:0) after 1m0s
[08:54:58.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:9:0)
[08:54:58.887] Timer tick, asking Buildbucket for the build status
[08:54:59.004] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:59.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:10:0) after 1m0s
[08:55:59.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:10:0)
[08:55:59.077] Timer tick, asking Buildbucket for the build status
[08:55:59.142] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:59.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:11:0) after 1m0s
[08:56:59.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:11:0)
[08:56:59.146] Timer tick, asking Buildbucket for the build status
[08:56:59.285] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:59.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:12:0) after 1m0s
[08:57:59.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:12:0)
[08:57:59.301] Timer tick, asking Buildbucket for the build status
[08:57:59.358] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:59.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:13:0) after 1m0s
[08:58:59.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:13:0)
[08:58:59.375] Timer tick, asking Buildbucket for the build status
[08:58:59.433] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:59.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:14:0) after 1m0s
[08:59:59.452] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:14:0)
[08:59:59.452] Timer tick, asking Buildbucket for the build status
[08:59:59.713] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:59.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:15:0) after 1m0s
[09:00:59.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:15:0)
[09:00:59.731] Timer tick, asking Buildbucket for the build status
[09:00:59.807] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:59.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:16:0) after 1m0s
[09:01:59.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:16:0)
[09:01:59.826] Timer tick, asking Buildbucket for the build status
[09:01:59.931] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:59.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:17:0) after 1m0s
[09:02:59.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:17:0)
[09:02:59.948] Timer tick, asking Buildbucket for the build status
[09:03:00.162] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:00.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:18:0) after 1m0s
[09:04:00.179] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:18:0)
[09:04:00.179] Timer tick, asking Buildbucket for the build status
[09:04:00.364] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:00.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:19:0) after 1m0s
[09:05:00.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:19:0)
[09:05:00.380] Timer tick, asking Buildbucket for the build status
[09:05:00.446] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:00.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:20:0) after 1m0s
[09:06:00.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:20:0)
[09:06:00.476] Timer tick, asking Buildbucket for the build status
[09:06:00.581] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:00.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:21:0) after 1m0s
[09:07:00.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:21:0)
[09:07:00.602] Timer tick, asking Buildbucket for the build status
[09:07:00.680] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:00.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:22:0) after 1m0s
[09:08:00.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:22:0)
[09:08:00.689] Timer tick, asking Buildbucket for the build status
[09:08:00.829] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:00.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:23:0) after 1m0s
[09:09:00.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:23:0)
[09:09:00.829] Timer tick, asking Buildbucket for the build status
[09:09:00.919] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:00.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:24:0) after 1m0s
[09:10:00.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:24:0)
[09:10:00.943] Timer tick, asking Buildbucket for the build status
[09:10:01.027] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:01.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:25:0) after 1m0s
[09:11:01.046] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:25:0)
[09:11:01.046] Timer tick, asking Buildbucket for the build status
[09:11:03.930] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:03.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:26:0) after 1m0s
[09:12:03.952] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:26:0)
[09:12:03.952] Timer tick, asking Buildbucket for the build status
[09:12:04.011] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:04.011] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:27:0) after 1m0s
[09:13:03.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:27:0)
[09:13:03.985] Timer tick, asking Buildbucket for the build status
[09:13:04.366] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:04.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:28:0) after 1m0s
[09:14:04.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:28:0)
[09:14:04.383] Timer tick, asking Buildbucket for the build status
[09:14:04.634] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:04.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:29:0) after 1m0s
[09:15:04.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:29:0)
[09:15:04.664] Timer tick, asking Buildbucket for the build status
[09:15:04.759] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:04.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:30:0) after 1m0s
[09:16:04.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:30:0)
[09:16:04.777] Timer tick, asking Buildbucket for the build status
[09:16:04.850] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:04.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:31:0) after 1m0s
[09:17:04.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:31:0)
[09:17:04.831] Timer tick, asking Buildbucket for the build status
[09:17:04.919] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:04.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:32:0) after 1m0s
[09:18:04.941] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:32:0)
[09:18:04.941] Timer tick, asking Buildbucket for the build status
[09:18:05.261] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:05.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:33:0) after 1m0s
[09:19:05.270] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:33:0)
[09:19:05.270] Timer tick, asking Buildbucket for the build status
[09:19:05.344] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:05.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:34:0) after 1m0s
[09:20:05.364] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:34:0)
[09:20:05.364] Timer tick, asking Buildbucket for the build status
[09:20:05.498] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:05.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:35:0) after 1m0s
[09:21:05.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:35:0)
[09:21:05.540] Timer tick, asking Buildbucket for the build status
[09:21:05.629] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:05.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:36:0) after 1m0s
[09:22:05.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:36:0)
[09:22:05.654] Timer tick, asking Buildbucket for the build status
[09:22:05.775] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:05.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:37:0) after 1m0s
[09:23:05.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:37:0)
[09:23:05.800] Timer tick, asking Buildbucket for the build status
[09:23:05.864] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:05.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:38:0) after 1m0s
[09:24:05.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:38:0)
[09:24:05.877] Timer tick, asking Buildbucket for the build status
[09:24:06.413] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:06.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:39:0) after 1m0s
[09:25:06.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:39:0)
[09:25:06.432] Timer tick, asking Buildbucket for the build status
[09:25:06.751] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:06.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:40:0) after 1m0s
[09:26:06.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:40:0)
[09:26:06.752] Timer tick, asking Buildbucket for the build status
[09:26:07.188] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:07.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:41:0) after 1m0s
[09:27:07.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:41:0)
[09:27:07.181] Timer tick, asking Buildbucket for the build status
[09:27:07.272] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:07.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:42:0) after 1m0s
[09:28:07.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:42:0)
[09:28:07.293] Timer tick, asking Buildbucket for the build status
[09:28:07.382] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:07.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:43:0) after 1m0s
[09:29:07.425] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:43:0)
[09:29:07.425] Timer tick, asking Buildbucket for the build status
[09:29:07.785] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:07.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:44:0) after 1m0s
[09:30:07.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:44:0)
[09:30:07.832] Timer tick, asking Buildbucket for the build status
[09:30:07.939] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:07.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:45:0) after 1m0s
[09:31:07.962] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:45:0)
[09:31:07.962] Timer tick, asking Buildbucket for the build status
[09:31:08.075] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:08.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:46:0) after 1m0s
[09:32:08.112] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:46:0)
[09:32:08.112] Timer tick, asking Buildbucket for the build status
[09:32:08.181] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:08.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:47:0) after 1m0s
[09:33:08.221] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:47:0)
[09:33:08.221] Timer tick, asking Buildbucket for the build status
[09:33:08.291] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:08.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:48:0) after 1m0s
[09:34:08.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:48:0)
[09:34:08.309] Timer tick, asking Buildbucket for the build status
[09:34:08.415] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:08.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:49:0) after 1m0s
[09:35:08.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:49:0)
[09:35:08.434] Timer tick, asking Buildbucket for the build status
[09:35:08.606] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:08.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:50:0) after 1m0s
[09:36:08.626] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:50:0)
[09:36:08.626] Timer tick, asking Buildbucket for the build status
[09:36:08.730] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:08.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:51:0) after 1m0s
[09:37:08.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:51:0)
[09:37:08.752] Timer tick, asking Buildbucket for the build status
[09:37:08.843] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:08.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:52:0) after 1m0s
[09:38:08.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:52:0)
[09:38:08.862] Timer tick, asking Buildbucket for the build status
[09:38:08.939] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:08.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:53:0) after 1m0s
[09:39:08.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:53:0)
[09:39:08.918] Timer tick, asking Buildbucket for the build status
[09:39:09.055] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:09.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:54:0) after 1m0s
[09:40:09.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:54:0)
[09:40:09.077] Timer tick, asking Buildbucket for the build status
[09:40:09.149] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:09.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:55:0) after 1m0s
[09:41:09.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:55:0)
[09:41:09.168] Timer tick, asking Buildbucket for the build status
[09:41:09.256] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:09.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:56:0) after 1m0s
[09:42:09.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:56:0)
[09:42:09.269] Timer tick, asking Buildbucket for the build status
[09:42:09.367] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:09.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:57:0) after 1m0s
[09:43:09.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:57:0)
[09:43:09.389] Timer tick, asking Buildbucket for the build status
[09:43:13.999] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:13.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:58:0) after 1m0s
[09:44:14.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:58:0)
[09:44:14.035] Timer tick, asking Buildbucket for the build status
[09:44:14.165] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:14.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:59:0) after 1m0s
[09:45:14.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:59:0)
[09:45:14.326] Timer tick, asking Buildbucket for the build status
[09:45:14.580] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:14.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:60:0) after 1m0s
[09:46:14.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:60:0)
[09:46:14.600] Timer tick, asking Buildbucket for the build status
[09:46:14.692] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:14.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:61:0) after 1m0s
[09:47:14.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:61:0)
[09:47:14.712] Timer tick, asking Buildbucket for the build status
[09:47:14.820] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:14.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:62:0) after 1m0s
[09:48:14.846] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:62:0)
[09:48:14.846] Timer tick, asking Buildbucket for the build status
[09:48:15.065] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:15.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:63:0) after 1m0s
[09:49:15.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:63:0)
[09:49:15.074] Timer tick, asking Buildbucket for the build status
[09:49:15.137] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:15.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:64:0) after 1m0s
[09:50:15.160] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:64:0)
[09:50:15.160] Timer tick, asking Buildbucket for the build status
[09:50:15.354] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:15.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:65:0) after 1m0s
[09:51:15.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:65:0)
[09:51:15.395] Timer tick, asking Buildbucket for the build status
[09:51:15.575] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:15.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:66:0) after 1m0s
[09:52:15.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:66:0)
[09:52:15.596] Timer tick, asking Buildbucket for the build status
[09:52:15.991] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:15.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:67:0) after 1m0s
[09:53:16.012] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:67:0)
[09:53:16.012] Timer tick, asking Buildbucket for the build status
[09:53:16.144] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:16.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:68:0) after 1m0s
[09:54:16.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:68:0)
[09:54:16.138] Timer tick, asking Buildbucket for the build status
[09:54:16.211] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:16.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:69:0) after 1m0s
[09:55:16.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:69:0)
[09:55:16.202] Timer tick, asking Buildbucket for the build status
[09:55:16.279] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:16.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:70:0) after 1m0s
[09:56:16.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:70:0)
[09:56:16.273] Timer tick, asking Buildbucket for the build status
[09:56:16.957] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:16.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:71:0) after 1m0s
[09:57:16.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:71:0)
[09:57:16.973] Timer tick, asking Buildbucket for the build status
[09:57:17.265] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:17.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:72:0) after 1m0s
[09:58:17.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:72:0)
[09:58:17.289] Timer tick, asking Buildbucket for the build status
[09:58:17.351] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:17.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:73:0) after 1m0s
[09:59:17.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:73:0)
[09:59:17.403] Timer tick, asking Buildbucket for the build status
[09:59:17.570] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:17.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:74:0) after 1m0s
[10:00:17.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:74:0)
[10:00:17.594] Timer tick, asking Buildbucket for the build status
[10:00:17.697] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:17.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:75:0) after 1m0s
[10:01:17.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:75:0)
[10:01:17.719] Timer tick, asking Buildbucket for the build status
[10:01:17.927] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:17.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:76:0) after 1m0s
[10:02:17.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:76:0)
[10:02:17.950] Timer tick, asking Buildbucket for the build status
[10:02:18.043] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:18.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:77:0) after 1m0s
[10:03:18.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:77:0)
[10:03:18.071] Timer tick, asking Buildbucket for the build status
[10:03:18.345] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:18.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:78:0) after 1m0s
[10:04:18.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:78:0)
[10:04:18.340] Timer tick, asking Buildbucket for the build status
[10:04:18.877] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:18.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:79:0) after 1m0s
[10:05:18.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:79:0)
[10:05:18.897] Timer tick, asking Buildbucket for the build status
[10:05:19.093] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:19.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:80:0) after 1m0s
[10:06:19.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:80:0)
[10:06:19.145] Timer tick, asking Buildbucket for the build status
[10:06:19.240] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:19.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:81:0) after 1m0s
[10:07:19.285] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:81:0)
[10:07:19.285] Timer tick, asking Buildbucket for the build status
[10:07:19.371] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:19.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:82:0) after 1m0s
[10:08:19.381] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:82:0)
[10:08:19.381] Timer tick, asking Buildbucket for the build status
[10:08:19.537] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:19.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:83:0) after 1m0s
[10:09:19.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:83:0)
[10:09:19.561] Timer tick, asking Buildbucket for the build status
[10:09:19.691] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:19.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:84:0) after 1m0s
[10:10:19.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:84:0)
[10:10:19.752] Timer tick, asking Buildbucket for the build status
[10:10:19.845] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:19.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:85:0) after 1m0s
[10:11:19.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:85:0)
[10:11:19.871] Timer tick, asking Buildbucket for the build status
[10:11:23.651] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:23.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:86:0) after 1m0s
[10:12:23.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:86:0)
[10:12:23.671] Timer tick, asking Buildbucket for the build status
[10:12:23.783] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:23.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:87:0) after 1m0s
[10:13:23.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:87:0)
[10:13:23.803] Timer tick, asking Buildbucket for the build status
[10:13:24.141] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:24.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:88:0) after 1m0s
[10:14:24.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:88:0)
[10:14:24.153] Timer tick, asking Buildbucket for the build status
[10:14:24.263] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:24.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:89:0) after 1m0s
[10:15:24.263] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:89:0)
[10:15:24.263] Timer tick, asking Buildbucket for the build status
[10:15:24.345] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:24.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:90:0) after 1m0s
[10:16:24.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:90:0)
[10:16:24.403] Timer tick, asking Buildbucket for the build status
[10:16:24.472] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:24.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:91:0) after 1m0s
[10:17:24.510] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:91:0)
[10:17:24.510] Timer tick, asking Buildbucket for the build status
[10:17:24.600] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:24.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:92:0) after 1m0s
[10:18:24.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:92:0)
[10:18:24.608] Timer tick, asking Buildbucket for the build status
[10:18:24.707] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:24.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:93:0) after 1m0s
[10:19:24.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:93:0)
[10:19:24.739] Timer tick, asking Buildbucket for the build status
[10:19:24.836] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:24.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:94:0) after 1m0s
[10:20:24.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:94:0)
[10:20:24.887] Timer tick, asking Buildbucket for the build status
[10:20:25.034] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:25.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:95:0) after 1m0s
[10:21:25.058] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:95:0)
[10:21:25.058] Timer tick, asking Buildbucket for the build status
[10:21:25.206] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:25.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:96:0) after 1m0s
[10:22:25.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:96:0)
[10:22:25.231] Timer tick, asking Buildbucket for the build status
[10:22:25.589] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:25.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:97:0) after 1m0s
[10:23:25.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:97:0)
[10:23:25.625] Timer tick, asking Buildbucket for the build status
[10:23:25.777] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:25.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:98:0) after 1m0s
[10:24:25.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:98:0)
[10:24:25.799] Timer tick, asking Buildbucket for the build status
[10:24:25.851] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:25.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:99:0) after 1m0s
[10:25:25.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:99:0)
[10:25:25.870] Timer tick, asking Buildbucket for the build status
[10:25:26.075] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:26.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:100:0) after 1m0s
[10:26:26.120] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:100:0)
[10:26:26.120] Timer tick, asking Buildbucket for the build status
[10:26:26.204] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:26.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:101:0) after 1m0s
[10:27:26.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:101:0)
[10:27:26.224] Timer tick, asking Buildbucket for the build status
[10:27:26.323] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:26.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:102:0) after 1m0s
[10:28:26.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:102:0)
[10:28:26.361] Timer tick, asking Buildbucket for the build status
[10:28:26.436] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:26.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:103:0) after 1m0s
[10:29:26.459] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:103:0)
[10:29:26.459] Timer tick, asking Buildbucket for the build status
[10:29:26.615] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:26.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:104:0) after 1m0s
[10:30:26.635] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:104:0)
[10:30:26.635] Timer tick, asking Buildbucket for the build status
[10:30:26.726] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:26.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:105:0) after 1m0s
[10:31:26.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:105:0)
[10:31:26.755] Timer tick, asking Buildbucket for the build status
[10:31:26.797] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:26.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:106:0) after 1m0s
[10:32:26.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:106:0)
[10:32:26.819] Timer tick, asking Buildbucket for the build status
[10:32:27.162] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:27.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:107:0) after 1m0s
[10:33:27.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:107:0)
[10:33:27.185] Timer tick, asking Buildbucket for the build status
[10:33:27.326] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:27.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:108:0) after 1m0s
[10:34:27.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:108:0)
[10:34:27.347] Timer tick, asking Buildbucket for the build status
[10:34:27.428] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:27.428] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:109:0) after 1m0s
[10:35:27.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:109:0)
[10:35:27.451] Timer tick, asking Buildbucket for the build status
[10:35:28.356] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:28.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:110:0) after 1m0s
[10:36:28.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:110:0)
[10:36:28.379] Timer tick, asking Buildbucket for the build status
[10:36:28.642] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:28.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:111:0) after 1m0s
[10:37:28.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:111:0)
[10:37:28.665] Timer tick, asking Buildbucket for the build status
[10:37:28.748] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:28.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:112:0) after 1m0s
[10:38:28.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:112:0)
[10:38:28.745] Timer tick, asking Buildbucket for the build status
[10:38:28.821] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:28.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:113:0) after 1m0s
[10:39:28.842] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:113:0)
[10:39:28.842] Timer tick, asking Buildbucket for the build status
[10:39:29.119] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:29.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:114:0) after 1m0s
[10:40:29.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:114:0)
[10:40:29.139] Timer tick, asking Buildbucket for the build status
[10:40:29.243] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:29.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:115:0) after 1m0s
[10:41:29.266] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:115:0)
[10:41:29.266] Timer tick, asking Buildbucket for the build status
[10:41:29.369] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:29.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:116:0) after 1m0s
[10:42:29.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:116:0)
[10:42:29.390] Timer tick, asking Buildbucket for the build status
[10:42:29.452] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:29.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:117:0) after 1m0s
[10:43:29.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:117:0)
[10:43:29.469] Timer tick, asking Buildbucket for the build status
[10:43:29.580] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:29.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:118:0) after 1m0s
[10:44:29.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:118:0)
[10:44:29.601] Timer tick, asking Buildbucket for the build status
[10:44:29.752] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:29.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:119:0) after 1m0s
[10:45:29.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:119:0)
[10:45:29.770] Timer tick, asking Buildbucket for the build status
[10:45:30.086] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:30.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:120:0) after 1m0s
[10:46:30.110] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:120:0)
[10:46:30.110] Timer tick, asking Buildbucket for the build status
[10:46:30.200] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:30.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:121:0) after 1m0s
[10:47:30.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:121:0)
[10:47:30.247] Timer tick, asking Buildbucket for the build status
[10:47:30.678] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:30.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:122:0) after 1m0s
[10:48:30.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:122:0)
[10:48:30.669] Timer tick, asking Buildbucket for the build status
[10:48:30.788] Build 8917574058667117392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:30.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083004120699158864:123:0) after 1m0s
[10:48:40.386] Received PubSub notification, asking Buildbucket for the build status
[10:48:40.452] Build 8917574058667117392: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:48:40.452] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553942920043647",
"created_by": "project:fuchsia",
"created_ts": "1553935677806960",
"failure_reason": "BUILD_FAILURE",
"id": "8917574058667117392",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"d880de2d19d46f7cfea7aa593602458440bc1e8d\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-sjwt\", \"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\": \"d880de2d19d46f7cfea7aa593602458440bc1e8d\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-sjwt\"], \"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\": [\"4226-cfe6277\"], \"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": "1553935700919696",
"status": "COMPLETED",
"status_changed_ts": "1553935701352793",
"tags": [
"builder:clang",
"buildset:commit/git/d880de2d19d46f7cfea7aa593602458440bc1e8d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/d880de2d19d46f7cfea7aa593602458440bc1e8d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083004120699158864",
"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/8917574058667117392/+/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:43e69a4528f36c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553942920108151",
"url": "https://ci.chromium.org/b/8917574058667117392",
"utcnow_ts": "1553942920442927"
}
}
[10:48:40.452] Invocation finished in 2h0m43.966823142s with status FAILED