[04:54:28.169] New invocation is queued and will start shortly
[04:54:29.245] Starting the invocation (attempt 1)
[04:54:29.281] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:54:29.282] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:54:29.282] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080663288373365920",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"733c8c40c81de5fcc8fab264ccb6bce6d39138c5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080663288373365920",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/733c8c40c81de5fcc8fab264ccb6bce6d39138c5",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/733c8c40c81de5fcc8fab264ccb6bce6d39138c5",
"gitiles_ref:refs/heads/master"
]
}
[04:54:30.776] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556168069381663",
"id": "8915233226439283024",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"733c8c40c81de5fcc8fab264ccb6bce6d39138c5\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556168069994546",
"tags": [
"builder:clang",
"buildset:commit/git/733c8c40c81de5fcc8fab264ccb6bce6d39138c5",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/733c8c40c81de5fcc8fab264ccb6bce6d39138c5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080663288373365920",
"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/8915233226439283024/+/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": "1556168069994733",
"url": "https://ci.chromium.org/b/8915233226439283024",
"utcnow_ts": "1556168070765594"
}
}
[04:54:30.776] Task URL: https://ci.chromium.org/b/8915233226439283024
[04:54:30.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:2:0) after 1m0s
[04:54:43.497] Received PubSub notification, asking Buildbucket for the build status
[04:54:43.553] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:30.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:2:0)
[04:55:30.796] Timer tick, asking Buildbucket for the build status
[04:55:30.896] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:30.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:4:0) after 1m0s
[04:56:30.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:4:0)
[04:56:30.877] Timer tick, asking Buildbucket for the build status
[04:56:30.966] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:30.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:5:0) after 1m0s
[04:57:30.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:5:0)
[04:57:30.951] Timer tick, asking Buildbucket for the build status
[04:57:31.040] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:31.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:6:0) after 1m0s
[04:58:31.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:6:0)
[04:58:31.068] Timer tick, asking Buildbucket for the build status
[04:58:31.252] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:31.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:7:0) after 1m0s
[04:59:31.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:7:0)
[04:59:31.228] Timer tick, asking Buildbucket for the build status
[04:59:31.271] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:31.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:8:0) after 1m0s
[05:00:31.290] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:8:0)
[05:00:31.290] Timer tick, asking Buildbucket for the build status
[05:00:31.400] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:31.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:9:0) after 1m0s
[05:01:31.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:9:0)
[05:01:31.393] Timer tick, asking Buildbucket for the build status
[05:01:31.479] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:31.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:10:0) after 1m0s
[05:02:31.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:10:0)
[05:02:31.468] Timer tick, asking Buildbucket for the build status
[05:02:31.553] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:31.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:11:0) after 1m0s
[05:03:31.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:11:0)
[05:03:31.579] Timer tick, asking Buildbucket for the build status
[05:03:31.636] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:31.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:12:0) after 1m0s
[05:04:31.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:12:0)
[05:04:31.644] Timer tick, asking Buildbucket for the build status
[05:04:31.706] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:31.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:13:0) after 1m0s
[05:05:31.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:13:0)
[05:05:31.725] Timer tick, asking Buildbucket for the build status
[05:05:31.765] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:31.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:14:0) after 1m0s
[05:06:31.787] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:14:0)
[05:06:31.787] Timer tick, asking Buildbucket for the build status
[05:06:31.848] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:31.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:15:0) after 1m0s
[05:07:31.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:15:0)
[05:07:31.873] Timer tick, asking Buildbucket for the build status
[05:07:31.981] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:31.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:16:0) after 1m0s
[05:08:31.977] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:16:0)
[05:08:31.977] Timer tick, asking Buildbucket for the build status
[05:08:32.069] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:32.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:17:0) after 1m0s
[05:09:32.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:17:0)
[05:09:32.089] Timer tick, asking Buildbucket for the build status
[05:09:32.350] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:32.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:18:0) after 1m0s
[05:10:32.352] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:18:0)
[05:10:32.352] Timer tick, asking Buildbucket for the build status
[05:10:32.463] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:32.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:19:0) after 1m0s
[05:11:32.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:19:0)
[05:11:32.487] Timer tick, asking Buildbucket for the build status
[05:11:32.545] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:32.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:20:0) after 1m0s
[05:12:32.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:20:0)
[05:12:32.559] Timer tick, asking Buildbucket for the build status
[05:12:32.610] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:32.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:21:0) after 1m0s
[05:13:32.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:21:0)
[05:13:32.603] Timer tick, asking Buildbucket for the build status
[05:13:32.659] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:32.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:22:0) after 1m0s
[05:14:32.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:22:0)
[05:14:32.676] Timer tick, asking Buildbucket for the build status
[05:14:32.753] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:32.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:23:0) after 1m0s
[05:15:32.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:23:0)
[05:15:32.772] Timer tick, asking Buildbucket for the build status
[05:15:32.833] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:32.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:24:0) after 1m0s
[05:16:32.816] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:24:0)
[05:16:32.816] Timer tick, asking Buildbucket for the build status
[05:16:32.872] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:32.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:25:0) after 1m0s
[05:17:32.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:25:0)
[05:17:32.908] Timer tick, asking Buildbucket for the build status
[05:17:32.953] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:32.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:26:0) after 1m0s
[05:18:32.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:26:0)
[05:18:32.981] Timer tick, asking Buildbucket for the build status
[05:18:33.182] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:33.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:27:0) after 1m0s
[05:19:33.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:27:0)
[05:19:33.209] Timer tick, asking Buildbucket for the build status
[05:19:33.262] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:33.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:28:0) after 1m0s
[05:20:33.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:28:0)
[05:20:33.292] Timer tick, asking Buildbucket for the build status
[05:20:33.368] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:33.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:29:0) after 1m0s
[05:21:33.364] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:29:0)
[05:21:33.364] Timer tick, asking Buildbucket for the build status
[05:21:33.475] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:33.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:30:0) after 1m0s
[05:22:33.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:30:0)
[05:22:33.496] Timer tick, asking Buildbucket for the build status
[05:22:33.543] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:33.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:31:0) after 1m0s
[05:23:33.566] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:31:0)
[05:23:33.566] Timer tick, asking Buildbucket for the build status
[05:23:33.611] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:33.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:32:0) after 1m0s
[05:24:33.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:32:0)
[05:24:33.607] Timer tick, asking Buildbucket for the build status
[05:24:33.728] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:33.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:33:0) after 1m0s
[05:25:33.747] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:33:0)
[05:25:33.747] Timer tick, asking Buildbucket for the build status
[05:25:33.789] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:33.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:34:0) after 1m0s
[05:26:33.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:34:0)
[05:26:33.792] Timer tick, asking Buildbucket for the build status
[05:26:33.848] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:33.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:35:0) after 1m0s
[05:27:33.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:35:0)
[05:27:33.870] Timer tick, asking Buildbucket for the build status
[05:27:39.889] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:39.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:36:0) after 1m0s
[05:28:39.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:36:0)
[05:28:39.917] Timer tick, asking Buildbucket for the build status
[05:28:41.140] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:41.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:37:0) after 1m0s
[05:29:41.125] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:37:0)
[05:29:41.125] Timer tick, asking Buildbucket for the build status
[05:29:41.183] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:41.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:38:0) after 1m0s
[05:30:41.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:38:0)
[05:30:41.215] Timer tick, asking Buildbucket for the build status
[05:30:41.308] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:41.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:39:0) after 1m0s
[05:31:41.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:39:0)
[05:31:41.332] Timer tick, asking Buildbucket for the build status
[05:31:41.383] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:41.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:40:0) after 1m0s
[05:32:41.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:40:0)
[05:32:41.393] Timer tick, asking Buildbucket for the build status
[05:32:41.459] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:41.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:41:0) after 1m0s
[05:33:41.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:41:0)
[05:33:41.479] Timer tick, asking Buildbucket for the build status
[05:33:41.523] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:41.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:42:0) after 1m0s
[05:34:41.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:42:0)
[05:34:41.524] Timer tick, asking Buildbucket for the build status
[05:34:41.598] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:41.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:43:0) after 1m0s
[05:35:41.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:43:0)
[05:35:41.604] Timer tick, asking Buildbucket for the build status
[05:35:41.639] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:41.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:44:0) after 1m0s
[05:36:41.631] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:44:0)
[05:36:41.631] Timer tick, asking Buildbucket for the build status
[05:36:41.701] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:41.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:45:0) after 1m0s
[05:37:41.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:45:0)
[05:37:41.705] Timer tick, asking Buildbucket for the build status
[05:37:41.789] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:41.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:46:0) after 1m0s
[05:38:41.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:46:0)
[05:38:41.795] Timer tick, asking Buildbucket for the build status
[05:38:41.849] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:41.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:47:0) after 1m0s
[05:39:41.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:47:0)
[05:39:41.871] Timer tick, asking Buildbucket for the build status
[05:39:41.978] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:41.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:48:0) after 1m0s
[05:40:41.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:48:0)
[05:40:41.998] Timer tick, asking Buildbucket for the build status
[05:40:42.086] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:42.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:49:0) after 1m0s
[05:41:42.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:49:0)
[05:41:42.087] Timer tick, asking Buildbucket for the build status
[05:41:42.135] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:42.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:50:0) after 1m0s
[05:42:42.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:50:0)
[05:42:42.156] Timer tick, asking Buildbucket for the build status
[05:42:42.282] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:42.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:51:0) after 1m0s
[05:43:42.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:51:0)
[05:43:42.321] Timer tick, asking Buildbucket for the build status
[05:43:42.367] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:42.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:52:0) after 1m0s
[05:44:42.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:52:0)
[05:44:42.348] Timer tick, asking Buildbucket for the build status
[05:44:42.449] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:42.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:53:0) after 1m0s
[05:45:42.467] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:53:0)
[05:45:42.467] Timer tick, asking Buildbucket for the build status
[05:45:42.502] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:42.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:54:0) after 1m0s
[05:46:42.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:54:0)
[05:46:42.489] Timer tick, asking Buildbucket for the build status
[05:46:42.626] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:42.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:55:0) after 1m0s
[05:47:42.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:55:0)
[05:47:42.620] Timer tick, asking Buildbucket for the build status
[05:47:42.672] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:42.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:56:0) after 1m0s
[05:48:42.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:56:0)
[05:48:42.685] Timer tick, asking Buildbucket for the build status
[05:48:42.759] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:42.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:57:0) after 1m0s
[05:49:42.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:57:0)
[05:49:42.779] Timer tick, asking Buildbucket for the build status
[05:49:42.824] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:42.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:58:0) after 1m0s
[05:50:42.825] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:58:0)
[05:50:42.825] Timer tick, asking Buildbucket for the build status
[05:50:42.892] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:42.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:59:0) after 1m0s
[05:51:42.915] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:59:0)
[05:51:42.915] Timer tick, asking Buildbucket for the build status
[05:51:42.995] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:42.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:60:0) after 1m0s
[05:52:42.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:60:0)
[05:52:42.996] Timer tick, asking Buildbucket for the build status
[05:52:43.164] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:43.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:61:0) after 1m0s
[05:53:43.184] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:61:0)
[05:53:43.184] Timer tick, asking Buildbucket for the build status
[05:53:43.240] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:43.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:62:0) after 1m0s
[05:54:43.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:62:0)
[05:54:43.302] Timer tick, asking Buildbucket for the build status
[05:54:43.382] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:43.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:63:0) after 1m0s
[05:55:43.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:63:0)
[05:55:43.368] Timer tick, asking Buildbucket for the build status
[05:55:43.510] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:43.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:64:0) after 1m0s
[05:56:43.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:64:0)
[05:56:43.581] Timer tick, asking Buildbucket for the build status
[05:56:43.699] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:43.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:65:0) after 1m0s
[05:57:43.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:65:0)
[05:57:43.719] Timer tick, asking Buildbucket for the build status
[05:57:43.959] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:43.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:66:0) after 1m0s
[05:58:43.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:66:0)
[05:58:43.981] Timer tick, asking Buildbucket for the build status
[05:58:44.032] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:44.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:67:0) after 1m0s
[05:59:44.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:67:0)
[05:59:44.065] Timer tick, asking Buildbucket for the build status
[05:59:44.122] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:44.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:68:0) after 1m0s
[06:00:44.150] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:68:0)
[06:00:44.150] Timer tick, asking Buildbucket for the build status
[06:00:44.187] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:44.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:69:0) after 1m0s
[06:01:44.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:69:0)
[06:01:44.198] Timer tick, asking Buildbucket for the build status
[06:01:44.294] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:44.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:70:0) after 1m0s
[06:02:44.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:70:0)
[06:02:44.317] Timer tick, asking Buildbucket for the build status
[06:02:44.608] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:44.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:71:0) after 1m0s
[06:03:44.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:71:0)
[06:03:44.628] Timer tick, asking Buildbucket for the build status
[06:03:44.778] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:44.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:72:0) after 1m0s
[06:04:44.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:72:0)
[06:04:44.796] Timer tick, asking Buildbucket for the build status
[06:04:44.879] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:44.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:73:0) after 1m0s
[06:05:44.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:73:0)
[06:05:44.888] Timer tick, asking Buildbucket for the build status
[06:05:44.965] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:44.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:74:0) after 1m0s
[06:06:44.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:74:0)
[06:06:44.988] Timer tick, asking Buildbucket for the build status
[06:06:45.029] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:45.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:75:0) after 1m0s
[06:07:45.082] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:75:0)
[06:07:45.082] Timer tick, asking Buildbucket for the build status
[06:07:45.221] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:45.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:76:0) after 1m0s
[06:08:45.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:76:0)
[06:08:45.198] Timer tick, asking Buildbucket for the build status
[06:08:45.261] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:45.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:77:0) after 1m0s
[06:09:45.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:77:0)
[06:09:45.348] Timer tick, asking Buildbucket for the build status
[06:09:45.557] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:45.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:78:0) after 1m0s
[06:10:45.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:78:0)
[06:10:45.590] Timer tick, asking Buildbucket for the build status
[06:10:45.757] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:45.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:79:0) after 1m0s
[06:11:45.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:79:0)
[06:11:45.751] Timer tick, asking Buildbucket for the build status
[06:11:45.870] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:45.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:80:0) after 1m0s
[06:12:45.890] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:80:0)
[06:12:45.890] Timer tick, asking Buildbucket for the build status
[06:12:45.942] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:45.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:81:0) after 1m0s
[06:13:46.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:81:0)
[06:13:46.067] Timer tick, asking Buildbucket for the build status
[06:13:46.132] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:46.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:82:0) after 1m0s
[06:14:46.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:82:0)
[06:14:46.159] Timer tick, asking Buildbucket for the build status
[06:14:46.304] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:46.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:83:0) after 1m0s
[06:15:46.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:83:0)
[06:15:46.315] Timer tick, asking Buildbucket for the build status
[06:15:46.485] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:46.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:84:0) after 1m0s
[06:16:46.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:84:0)
[06:16:46.489] Timer tick, asking Buildbucket for the build status
[06:16:46.528] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:46.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:85:0) after 1m0s
[06:17:46.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:85:0)
[06:17:46.516] Timer tick, asking Buildbucket for the build status
[06:17:46.611] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:46.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:86:0) after 1m0s
[06:18:46.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:86:0)
[06:18:46.627] Timer tick, asking Buildbucket for the build status
[06:18:46.683] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:46.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:87:0) after 1m0s
[06:19:46.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:87:0)
[06:19:46.720] Timer tick, asking Buildbucket for the build status
[06:19:46.805] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:46.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:88:0) after 1m0s
[06:20:46.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:88:0)
[06:20:46.808] Timer tick, asking Buildbucket for the build status
[06:20:46.888] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:46.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:89:0) after 1m0s
[06:21:46.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:89:0)
[06:21:46.934] Timer tick, asking Buildbucket for the build status
[06:21:47.081] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:47.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:90:0) after 1m0s
[06:22:47.078] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:90:0)
[06:22:47.078] Timer tick, asking Buildbucket for the build status
[06:22:47.169] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:47.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:91:0) after 1m0s
[06:23:47.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:91:0)
[06:23:47.213] Timer tick, asking Buildbucket for the build status
[06:23:47.263] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:47.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:92:0) after 1m0s
[06:24:47.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:92:0)
[06:24:47.286] Timer tick, asking Buildbucket for the build status
[06:24:47.446] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:47.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:93:0) after 1m0s
[06:25:47.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:93:0)
[06:25:47.489] Timer tick, asking Buildbucket for the build status
[06:25:47.534] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:47.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:94:0) after 1m0s
[06:26:47.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:94:0)
[06:26:47.526] Timer tick, asking Buildbucket for the build status
[06:26:47.586] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:47.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:95:0) after 1m0s
[06:27:47.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:95:0)
[06:27:47.601] Timer tick, asking Buildbucket for the build status
[06:27:47.682] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:47.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:96:0) after 1m0s
[06:28:47.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:96:0)
[06:28:47.704] Timer tick, asking Buildbucket for the build status
[06:28:47.754] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:47.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:97:0) after 1m0s
[06:29:47.747] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:97:0)
[06:29:47.747] Timer tick, asking Buildbucket for the build status
[06:29:47.832] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:47.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:98:0) after 1m0s
[06:30:47.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:98:0)
[06:30:47.838] Timer tick, asking Buildbucket for the build status
[06:30:47.906] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:47.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:99:0) after 1m0s
[06:31:47.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:99:0)
[06:31:47.928] Timer tick, asking Buildbucket for the build status
[06:31:48.029] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:48.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:100:0) after 1m0s
[06:32:48.062] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:100:0)
[06:32:48.062] Timer tick, asking Buildbucket for the build status
[06:32:48.218] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:48.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:101:0) after 1m0s
[06:33:48.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:101:0)
[06:33:48.257] Timer tick, asking Buildbucket for the build status
[06:33:48.350] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:48.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:102:0) after 1m0s
[06:34:48.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:102:0)
[06:34:48.373] Timer tick, asking Buildbucket for the build status
[06:34:48.408] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:48.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:103:0) after 1m0s
[06:35:48.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:103:0)
[06:35:48.427] Timer tick, asking Buildbucket for the build status
[06:35:48.476] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:48.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:104:0) after 1m0s
[06:36:48.498] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:104:0)
[06:36:48.498] Timer tick, asking Buildbucket for the build status
[06:36:48.675] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:48.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:105:0) after 1m0s
[06:37:48.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:105:0)
[06:37:48.697] Timer tick, asking Buildbucket for the build status
[06:37:48.749] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:48.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:106:0) after 1m0s
[06:38:48.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:106:0)
[06:38:48.737] Timer tick, asking Buildbucket for the build status
[06:38:48.774] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:48.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:107:0) after 1m0s
[06:39:48.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:107:0)
[06:39:48.796] Timer tick, asking Buildbucket for the build status
[06:39:49.164] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:49.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:108:0) after 1m0s
[06:40:49.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:108:0)
[06:40:49.204] Timer tick, asking Buildbucket for the build status
[06:40:49.288] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:49.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:109:0) after 1m0s
[06:41:49.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:109:0)
[06:41:49.300] Timer tick, asking Buildbucket for the build status
[06:41:49.566] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:49.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:110:0) after 1m0s
[06:42:49.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:110:0)
[06:42:49.592] Timer tick, asking Buildbucket for the build status
[06:42:49.773] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:49.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:111:0) after 1m0s
[06:43:49.746] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:111:0)
[06:43:49.746] Timer tick, asking Buildbucket for the build status
[06:43:49.856] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:49.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:112:0) after 1m0s
[06:44:49.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:112:0)
[06:44:49.884] Timer tick, asking Buildbucket for the build status
[06:44:49.925] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:49.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:113:0) after 1m0s
[06:45:49.946] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:113:0)
[06:45:49.947] Timer tick, asking Buildbucket for the build status
[06:45:50.036] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:50.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:114:0) after 1m0s
[06:46:50.062] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:114:0)
[06:46:50.062] Timer tick, asking Buildbucket for the build status
[06:46:50.108] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:50.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:115:0) after 1m0s
[06:47:50.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:115:0)
[06:47:50.083] Timer tick, asking Buildbucket for the build status
[06:47:50.354] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:50.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:116:0) after 1m0s
[06:48:50.376] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:116:0)
[06:48:50.376] Timer tick, asking Buildbucket for the build status
[06:48:50.515] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:50.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:117:0) after 1m0s
[06:49:50.544] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:117:0)
[06:49:50.544] Timer tick, asking Buildbucket for the build status
[06:49:50.668] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:50.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:118:0) after 1m0s
[06:50:50.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:118:0)
[06:50:50.672] Timer tick, asking Buildbucket for the build status
[06:50:50.732] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:50.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:119:0) after 1m0s
[06:51:50.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:119:0)
[06:51:50.756] Timer tick, asking Buildbucket for the build status
[06:51:50.795] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:50.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:120:0) after 1m0s
[06:52:50.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:120:0)
[06:52:50.827] Timer tick, asking Buildbucket for the build status
[06:52:50.895] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:50.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:121:0) after 1m0s
[06:53:50.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:121:0)
[06:53:50.889] Timer tick, asking Buildbucket for the build status
[06:53:51.003] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:51.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:122:0) after 1m0s
[06:54:51.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:122:0)
[06:54:51.031] Timer tick, asking Buildbucket for the build status
[06:54:51.100] Build 8915233226439283024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:51.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080663288373365920:123:0) after 1m0s
[06:55:16.210] Received PubSub notification, asking Buildbucket for the build status
[06:55:16.262] Build 8915233226439283024: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[06:55:16.262] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556175315861723",
"created_by": "project:fuchsia",
"created_ts": "1556168069381663",
"failure_reason": "BUILD_FAILURE",
"id": "8915233226439283024",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"733c8c40c81de5fcc8fab264ccb6bce6d39138c5\"}}",
"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-rk3n\", \"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\": \"733c8c40c81de5fcc8fab264ccb6bce6d39138c5\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-rk3n\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4274-a0a5f8c\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1556168082204119",
"status": "COMPLETED",
"status_changed_ts": "1556168083152915",
"tags": [
"builder:clang",
"buildset:commit/git/733c8c40c81de5fcc8fab264ccb6bce6d39138c5",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/733c8c40c81de5fcc8fab264ccb6bce6d39138c5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080663288373365920",
"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/8915233226439283024/+/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:446ba9dfb5628610",
"user_agent:luci-scheduler"
],
"updated_ts": "1556175316009042",
"url": "https://ci.chromium.org/b/8915233226439283024",
"utcnow_ts": "1556175316245536"
}
}
[06:55:16.262] Invocation finished in 2h0m48.109285725s with status FAILED