[11:55:30.344] New invocation is queued and will start shortly
[11:55:31.494] Starting the invocation (attempt 1)
[11:55:31.544] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:55:31.544] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:55:31.544] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9084079483792070656",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"23629385f10d952aadad4f829c936ae879436386\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9084079483792070656",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/23629385f10d952aadad4f829c936ae879436386",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/23629385f10d952aadad4f829c936ae879436386",
"gitiles_ref:refs/heads/master"
]
}
[11:55:32.938] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1552910131673231",
"id": "8918649421728965792",
"lease_expiration_ts": "1553036132552186",
"lease_key": "10425835",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"23629385f10d952aadad4f829c936ae879436386\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552910132759536",
"tags": [
"builder:clang",
"buildset:commit/git/23629385f10d952aadad4f829c936ae879436386",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/23629385f10d952aadad4f829c936ae879436386",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084079483792070656",
"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/8918649421728965792/+/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:43a979abe9d25510",
"user_agent:luci-scheduler"
],
"updated_ts": "1552910132759640",
"url": "https://ci.chromium.org/b/8918649421728965792",
"utcnow_ts": "1552910132930345"
}
}
[11:55:32.938] Task URL: https://ci.chromium.org/b/8918649421728965792
[11:55:32.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:2:0) after 1m0s
[11:55:58.655] Received PubSub notification, asking Buildbucket for the build status
[11:55:58.723] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:32.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:2:0)
[11:56:32.923] Timer tick, asking Buildbucket for the build status
[11:56:33.075] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:33.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:4:0) after 1m0s
[11:57:33.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:4:0)
[11:57:33.101] Timer tick, asking Buildbucket for the build status
[11:57:33.273] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:33.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:5:0) after 1m0s
[11:58:33.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:5:0)
[11:58:33.299] Timer tick, asking Buildbucket for the build status
[11:58:33.358] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:33.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:6:0) after 1m0s
[11:59:33.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:6:0)
[11:59:33.374] Timer tick, asking Buildbucket for the build status
[11:59:33.442] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:33.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:7:0) after 1m0s
[12:00:33.462] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:7:0)
[12:00:33.462] Timer tick, asking Buildbucket for the build status
[12:00:33.528] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:33.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:8:0) after 1m0s
[12:01:33.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:8:0)
[12:01:33.548] Timer tick, asking Buildbucket for the build status
[12:01:33.776] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:33.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:9:0) after 1m0s
[12:02:33.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:9:0)
[12:02:33.774] Timer tick, asking Buildbucket for the build status
[12:02:33.859] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:33.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:10:0) after 1m0s
[12:03:33.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:10:0)
[12:03:33.878] Timer tick, asking Buildbucket for the build status
[12:03:34.061] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:34.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:11:0) after 1m0s
[12:04:34.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:11:0)
[12:04:34.077] Timer tick, asking Buildbucket for the build status
[12:04:34.255] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:34.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:12:0) after 1m0s
[12:05:34.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:12:0)
[12:05:34.273] Timer tick, asking Buildbucket for the build status
[12:05:34.406] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:34.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:13:0) after 1m0s
[12:06:34.425] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:13:0)
[12:06:34.425] Timer tick, asking Buildbucket for the build status
[12:06:34.475] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:34.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:14:0) after 1m0s
[12:07:34.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:14:0)
[12:07:34.508] Timer tick, asking Buildbucket for the build status
[12:07:34.568] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:34.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:15:0) after 1m0s
[12:08:34.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:15:0)
[12:08:34.583] Timer tick, asking Buildbucket for the build status
[12:08:34.784] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:34.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:16:0) after 1m0s
[12:09:34.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:16:0)
[12:09:34.789] Timer tick, asking Buildbucket for the build status
[12:09:34.835] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:34.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:17:0) after 1m0s
[12:10:34.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:17:0)
[12:10:34.852] Timer tick, asking Buildbucket for the build status
[12:10:34.901] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:34.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:18:0) after 1m0s
[12:11:34.895] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:18:0)
[12:11:34.895] Timer tick, asking Buildbucket for the build status
[12:11:34.957] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:34.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:19:0) after 1m0s
[12:12:34.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:19:0)
[12:12:34.949] Timer tick, asking Buildbucket for the build status
[12:12:35.015] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:35.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:20:0) after 1m0s
[12:13:35.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:20:0)
[12:13:35.035] Timer tick, asking Buildbucket for the build status
[12:13:35.110] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:35.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:21:0) after 1m0s
[12:14:35.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:21:0)
[12:14:35.129] Timer tick, asking Buildbucket for the build status
[12:14:35.192] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:35.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:22:0) after 1m0s
[12:15:35.240] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:22:0)
[12:15:35.240] Timer tick, asking Buildbucket for the build status
[12:15:35.479] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:35.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:23:0) after 1m0s
[12:16:35.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:23:0)
[12:16:35.477] Timer tick, asking Buildbucket for the build status
[12:16:35.523] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:35.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:24:0) after 1m0s
[12:17:35.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:24:0)
[12:17:35.508] Timer tick, asking Buildbucket for the build status
[12:17:35.593] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:35.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:25:0) after 1m0s
[12:18:35.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:25:0)
[12:18:35.567] Timer tick, asking Buildbucket for the build status
[12:18:35.607] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:35.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:26:0) after 1m0s
[12:19:35.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:26:0)
[12:19:35.628] Timer tick, asking Buildbucket for the build status
[12:19:35.735] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:35.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:27:0) after 1m0s
[12:20:35.752] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:27:0)
[12:20:35.752] Timer tick, asking Buildbucket for the build status
[12:20:35.803] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:35.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:28:0) after 1m0s
[12:21:35.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:28:0)
[12:21:35.836] Timer tick, asking Buildbucket for the build status
[12:21:36.075] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:36.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:29:0) after 1m0s
[12:22:36.076] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:29:0)
[12:22:36.076] Timer tick, asking Buildbucket for the build status
[12:22:36.117] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:36.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:30:0) after 1m0s
[12:23:36.142] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:30:0)
[12:23:36.142] Timer tick, asking Buildbucket for the build status
[12:23:36.208] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:36.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:31:0) after 1m0s
[12:24:36.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:31:0)
[12:24:36.192] Timer tick, asking Buildbucket for the build status
[12:24:36.353] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:36.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:32:0) after 1m0s
[12:25:36.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:32:0)
[12:25:36.361] Timer tick, asking Buildbucket for the build status
[12:25:36.591] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:36.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:33:0) after 1m0s
[12:26:36.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:33:0)
[12:26:36.595] Timer tick, asking Buildbucket for the build status
[12:26:36.681] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:36.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:34:0) after 1m0s
[12:27:36.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:34:0)
[12:27:36.685] Timer tick, asking Buildbucket for the build status
[12:27:36.804] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:36.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:35:0) after 1m0s
[12:28:36.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:35:0)
[12:28:36.826] Timer tick, asking Buildbucket for the build status
[12:28:36.893] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:36.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:36:0) after 1m0s
[12:29:36.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:36:0)
[12:29:36.914] Timer tick, asking Buildbucket for the build status
[12:29:36.962] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:36.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:37:0) after 1m0s
[12:30:36.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:37:0)
[12:30:36.989] Timer tick, asking Buildbucket for the build status
[12:30:37.299] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:37.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:38:0) after 1m0s
[12:31:37.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:38:0)
[12:31:37.291] Timer tick, asking Buildbucket for the build status
[12:31:37.347] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:37.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:39:0) after 1m0s
[12:32:37.339] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:39:0)
[12:32:37.339] Timer tick, asking Buildbucket for the build status
[12:32:37.397] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:37.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:40:0) after 1m0s
[12:33:37.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:40:0)
[12:33:37.407] Timer tick, asking Buildbucket for the build status
[12:33:37.482] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:37.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:41:0) after 1m0s
[12:34:37.505] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:41:0)
[12:34:37.505] Timer tick, asking Buildbucket for the build status
[12:34:37.568] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:37.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:42:0) after 1m0s
[12:35:37.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:42:0)
[12:35:37.590] Timer tick, asking Buildbucket for the build status
[12:35:37.871] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:37.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:43:0) after 1m0s
[12:36:37.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:43:0)
[12:36:37.865] Timer tick, asking Buildbucket for the build status
[12:36:37.914] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:37.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:44:0) after 1m0s
[12:37:37.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:44:0)
[12:37:37.908] Timer tick, asking Buildbucket for the build status
[12:37:37.973] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:37.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:45:0) after 1m0s
[12:38:37.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:45:0)
[12:38:37.973] Timer tick, asking Buildbucket for the build status
[12:38:38.012] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:38.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:46:0) after 1m0s
[12:39:38.025] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:46:0)
[12:39:38.025] Timer tick, asking Buildbucket for the build status
[12:39:38.088] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:38.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:47:0) after 1m0s
[12:40:38.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:47:0)
[12:40:38.097] Timer tick, asking Buildbucket for the build status
[12:40:38.141] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:38.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:48:0) after 1m0s
[12:41:38.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:48:0)
[12:41:38.152] Timer tick, asking Buildbucket for the build status
[12:41:38.213] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:38.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:49:0) after 1m0s
[12:42:38.227] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:49:0)
[12:42:38.227] Timer tick, asking Buildbucket for the build status
[12:42:38.275] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:38.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:50:0) after 1m0s
[12:43:38.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:50:0)
[12:43:38.311] Timer tick, asking Buildbucket for the build status
[12:43:38.379] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:38.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:51:0) after 1m0s
[12:44:38.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:51:0)
[12:44:38.416] Timer tick, asking Buildbucket for the build status
[12:44:38.554] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:38.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:52:0) after 1m0s
[12:45:38.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:52:0)
[12:45:38.529] Timer tick, asking Buildbucket for the build status
[12:45:38.684] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:38.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:53:0) after 1m0s
[12:46:38.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:53:0)
[12:46:38.686] Timer tick, asking Buildbucket for the build status
[12:46:38.739] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:38.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:54:0) after 1m0s
[12:47:38.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:54:0)
[12:47:38.754] Timer tick, asking Buildbucket for the build status
[12:47:38.985] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:38.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:55:0) after 1m0s
[12:48:39.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:55:0)
[12:48:39.006] Timer tick, asking Buildbucket for the build status
[12:48:39.270] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:39.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:56:0) after 1m0s
[12:49:39.252] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:56:0)
[12:49:39.252] Timer tick, asking Buildbucket for the build status
[12:49:39.381] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:39.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:57:0) after 1m0s
[12:50:39.400] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:57:0)
[12:50:39.400] Timer tick, asking Buildbucket for the build status
[12:50:39.460] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:39.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:58:0) after 1m0s
[12:51:39.452] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:58:0)
[12:51:39.452] Timer tick, asking Buildbucket for the build status
[12:51:39.715] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:39.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:59:0) after 1m0s
[12:52:39.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:59:0)
[12:52:39.739] Timer tick, asking Buildbucket for the build status
[12:52:39.804] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:39.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:60:0) after 1m0s
[12:53:39.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:60:0)
[12:53:39.823] Timer tick, asking Buildbucket for the build status
[12:53:39.886] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:39.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:61:0) after 1m0s
[12:54:39.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:61:0)
[12:54:39.910] Timer tick, asking Buildbucket for the build status
[12:54:39.989] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:39.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:62:0) after 1m0s
[12:55:40.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:62:0)
[12:55:40.009] Timer tick, asking Buildbucket for the build status
[12:55:40.105] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:40.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:63:0) after 1m0s
[12:56:40.110] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:63:0)
[12:56:40.110] Timer tick, asking Buildbucket for the build status
[12:56:40.153] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:40.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:64:0) after 1m0s
[12:57:40.141] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:64:0)
[12:57:40.141] Timer tick, asking Buildbucket for the build status
[12:57:40.265] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:40.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:65:0) after 1m0s
[12:58:40.262] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:65:0)
[12:58:40.262] Timer tick, asking Buildbucket for the build status
[12:58:40.325] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:40.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:66:0) after 1m0s
[12:59:40.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:66:0)
[12:59:40.348] Timer tick, asking Buildbucket for the build status
[12:59:40.564] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:40.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:67:0) after 1m0s
[13:00:40.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:67:0)
[13:00:40.563] Timer tick, asking Buildbucket for the build status
[13:00:40.682] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:40.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:68:0) after 1m0s
[13:01:40.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:68:0)
[13:01:40.673] Timer tick, asking Buildbucket for the build status
[13:01:40.761] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:40.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:69:0) after 1m0s
[13:02:40.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:69:0)
[13:02:40.769] Timer tick, asking Buildbucket for the build status
[13:02:40.809] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:40.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:70:0) after 1m0s
[13:03:40.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:70:0)
[13:03:40.850] Timer tick, asking Buildbucket for the build status
[13:03:40.915] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:40.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:71:0) after 1m0s
[13:04:40.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:71:0)
[13:04:40.970] Timer tick, asking Buildbucket for the build status
[13:04:41.009] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:41.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:72:0) after 1m0s
[13:05:41.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:72:0)
[13:05:41.015] Timer tick, asking Buildbucket for the build status
[13:05:41.104] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:41.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:73:0) after 1m0s
[13:06:41.143] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:73:0)
[13:06:41.143] Timer tick, asking Buildbucket for the build status
[13:06:41.265] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:41.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:74:0) after 1m0s
[13:07:41.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:74:0)
[13:07:41.247] Timer tick, asking Buildbucket for the build status
[13:07:41.356] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:41.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:75:0) after 1m0s
[13:08:41.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:75:0)
[13:08:41.377] Timer tick, asking Buildbucket for the build status
[13:08:41.653] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:41.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:76:0) after 1m0s
[13:09:41.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:76:0)
[13:09:41.692] Timer tick, asking Buildbucket for the build status
[13:09:41.896] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:41.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:77:0) after 1m0s
[13:10:41.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:77:0)
[13:10:41.914] Timer tick, asking Buildbucket for the build status
[13:10:41.999] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:41.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:78:0) after 1m0s
[13:11:41.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:78:0)
[13:11:41.979] Timer tick, asking Buildbucket for the build status
[13:11:42.112] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:42.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:79:0) after 1m0s
[13:12:42.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:79:0)
[13:12:42.138] Timer tick, asking Buildbucket for the build status
[13:12:42.402] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:42.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:80:0) after 1m0s
[13:13:42.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:80:0)
[13:13:42.423] Timer tick, asking Buildbucket for the build status
[13:13:42.558] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:42.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:81:0) after 1m0s
[13:14:42.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:81:0)
[13:14:42.583] Timer tick, asking Buildbucket for the build status
[13:14:42.709] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:42.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:82:0) after 1m0s
[13:15:42.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:82:0)
[13:15:42.689] Timer tick, asking Buildbucket for the build status
[13:15:42.755] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:42.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:83:0) after 1m0s
[13:16:42.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:83:0)
[13:16:42.793] Timer tick, asking Buildbucket for the build status
[13:16:42.870] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:42.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:84:0) after 1m0s
[13:17:42.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:84:0)
[13:17:42.897] Timer tick, asking Buildbucket for the build status
[13:17:42.950] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:42.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:85:0) after 1m0s
[13:18:43.003] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:85:0)
[13:18:43.003] Timer tick, asking Buildbucket for the build status
[13:18:43.070] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:43.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:86:0) after 1m0s
[13:19:43.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:86:0)
[13:19:43.096] Timer tick, asking Buildbucket for the build status
[13:19:43.180] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:43.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:87:0) after 1m0s
[13:20:43.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:87:0)
[13:20:43.173] Timer tick, asking Buildbucket for the build status
[13:20:43.282] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:43.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:88:0) after 1m0s
[13:21:43.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:88:0)
[13:21:43.268] Timer tick, asking Buildbucket for the build status
[13:21:43.381] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:43.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:89:0) after 1m0s
[13:22:43.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:89:0)
[13:22:43.411] Timer tick, asking Buildbucket for the build status
[13:22:43.449] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:43.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:90:0) after 1m0s
[13:23:43.455] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:90:0)
[13:23:43.455] Timer tick, asking Buildbucket for the build status
[13:23:43.529] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:43.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:91:0) after 1m0s
[13:24:43.554] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:91:0)
[13:24:43.554] Timer tick, asking Buildbucket for the build status
[13:24:43.609] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:43.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:92:0) after 1m0s
[13:25:43.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:92:0)
[13:25:43.591] Timer tick, asking Buildbucket for the build status
[13:25:43.648] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:43.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:93:0) after 1m0s
[13:26:43.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:93:0)
[13:26:43.671] Timer tick, asking Buildbucket for the build status
[13:26:43.763] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:43.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:94:0) after 1m0s
[13:27:43.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:94:0)
[13:27:43.785] Timer tick, asking Buildbucket for the build status
[13:27:43.848] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:43.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:95:0) after 1m0s
[13:28:43.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:95:0)
[13:28:43.820] Timer tick, asking Buildbucket for the build status
[13:28:43.859] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:43.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:96:0) after 1m0s
[13:29:43.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:96:0)
[13:29:43.881] Timer tick, asking Buildbucket for the build status
[13:29:43.946] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:43.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:97:0) after 1m0s
[13:30:43.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:97:0)
[13:30:43.970] Timer tick, asking Buildbucket for the build status
[13:30:44.072] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:44.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:98:0) after 1m0s
[13:31:44.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:98:0)
[13:31:44.083] Timer tick, asking Buildbucket for the build status
[13:31:44.154] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:44.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:99:0) after 1m0s
[13:32:44.175] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:99:0)
[13:32:44.175] Timer tick, asking Buildbucket for the build status
[13:32:44.277] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:44.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:100:0) after 1m0s
[13:33:44.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:100:0)
[13:33:44.298] Timer tick, asking Buildbucket for the build status
[13:33:44.387] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:44.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:101:0) after 1m0s
[13:34:44.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:101:0)
[13:34:44.402] Timer tick, asking Buildbucket for the build status
[13:34:44.488] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:44.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:102:0) after 1m0s
[13:35:44.545] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:102:0)
[13:35:44.545] Timer tick, asking Buildbucket for the build status
[13:35:44.593] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:44.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:103:0) after 1m0s
[13:36:44.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:103:0)
[13:36:44.577] Timer tick, asking Buildbucket for the build status
[13:36:44.797] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:44.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:104:0) after 1m0s
[13:37:44.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:104:0)
[13:37:44.813] Timer tick, asking Buildbucket for the build status
[13:37:44.868] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:44.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:105:0) after 1m0s
[13:38:44.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:105:0)
[13:38:44.886] Timer tick, asking Buildbucket for the build status
[13:38:44.961] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:44.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:106:0) after 1m0s
[13:39:44.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:106:0)
[13:39:44.975] Timer tick, asking Buildbucket for the build status
[13:39:45.156] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:45.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:107:0) after 1m0s
[13:40:45.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:107:0)
[13:40:45.177] Timer tick, asking Buildbucket for the build status
[13:40:45.215] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:45.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:108:0) after 1m0s
[13:41:45.235] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:108:0)
[13:41:45.235] Timer tick, asking Buildbucket for the build status
[13:41:45.318] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:45.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:109:0) after 1m0s
[13:42:45.339] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:109:0)
[13:42:45.339] Timer tick, asking Buildbucket for the build status
[13:42:45.382] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:45.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:110:0) after 1m0s
[13:43:45.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:110:0)
[13:43:45.405] Timer tick, asking Buildbucket for the build status
[13:43:45.507] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:45.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:111:0) after 1m0s
[13:44:45.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:111:0)
[13:44:45.486] Timer tick, asking Buildbucket for the build status
[13:44:45.797] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:45.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:112:0) after 1m0s
[13:45:45.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:112:0)
[13:45:45.815] Timer tick, asking Buildbucket for the build status
[13:45:45.856] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:45.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:113:0) after 1m0s
[13:46:45.842] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:113:0)
[13:46:45.842] Timer tick, asking Buildbucket for the build status
[13:46:45.915] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:45.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:114:0) after 1m0s
[13:47:45.895] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:114:0)
[13:47:45.895] Timer tick, asking Buildbucket for the build status
[13:47:46.101] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:46.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:115:0) after 1m0s
[13:48:46.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:115:0)
[13:48:46.115] Timer tick, asking Buildbucket for the build status
[13:48:46.161] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:46.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:116:0) after 1m0s
[13:49:46.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:116:0)
[13:49:46.177] Timer tick, asking Buildbucket for the build status
[13:49:46.233] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:46.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:117:0) after 1m0s
[13:50:46.211] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:117:0)
[13:50:46.211] Timer tick, asking Buildbucket for the build status
[13:50:46.334] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:46.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:118:0) after 1m0s
[13:51:46.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:118:0)
[13:51:46.316] Timer tick, asking Buildbucket for the build status
[13:51:46.383] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:46.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:119:0) after 1m0s
[13:52:46.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:119:0)
[13:52:46.411] Timer tick, asking Buildbucket for the build status
[13:52:46.493] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:46.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:120:0) after 1m0s
[13:53:46.494] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:120:0)
[13:53:46.494] Timer tick, asking Buildbucket for the build status
[13:53:46.560] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:46.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:121:0) after 1m0s
[13:54:46.586] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:121:0)
[13:54:46.586] Timer tick, asking Buildbucket for the build status
[13:54:46.636] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:46.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:122:0) after 1m0s
[13:55:46.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:122:0)
[13:55:46.715] Timer tick, asking Buildbucket for the build status
[13:55:46.805] Build 8918649421728965792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:46.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084079483792070656:123:0) after 1m0s
[13:56:27.625] Received PubSub notification, asking Buildbucket for the build status
[13:56:27.745] Build 8918649421728965792: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:56:27.745] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1552917386917906",
"created_by": "project:fuchsia",
"created_ts": "1552910131673231",
"failure_reason": "BUILD_FAILURE",
"id": "8918649421728965792",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"23629385f10d952aadad4f829c936ae879436386\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"image\": [\"fuchsia-debian-9-drawfork-v20181107-ee01d6c\"], \"kvm\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4201-b04f7f8\"], \"machine_type\": [\"n1-standard-1\"], \"gpu\": [\"none\"], \"cores\": [\"1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"id\": [\"fuchsia-debian-1-03c1d9de-us-central1-c-f5db\"], \"pool\": [\"luci.fuchsia.prod\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"Failure buildbucket.run\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"recipe\": \"buildbucket_trigger\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"timeout\": 7200.0, \"path_config\": \"generic\", \"revision\": \"23629385f10d952aadad4f829c936ae879436386\", \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-03c1d9de-us-central1-c-f5db\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1552910158051038",
"status": "COMPLETED",
"status_changed_ts": "1552917387419402",
"tags": [
"builder:clang",
"buildset:commit/git/23629385f10d952aadad4f829c936ae879436386",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/23629385f10d952aadad4f829c936ae879436386",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084079483792070656",
"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/8918649421728965792/+/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:43a979abe9d25510",
"user_agent:luci-scheduler"
],
"updated_ts": "1552917387421829",
"url": "https://ci.chromium.org/b/8918649421728965792",
"utcnow_ts": "1552917387702218"
}
}
[13:56:27.745] Invocation finished in 2h0m57.422688132s with status FAILED