[01:12:10.091] New invocation is queued and will start shortly
[01:12:11.304] Starting the invocation (attempt 1)
[01:12:11.372] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:12:11.372] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:12:11.372] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9084482346956459120",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"23b37bf3628e6ce3e543ec2dc0713d217fba5ce8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9084482346956459120",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/23b37bf3628e6ce3e543ec2dc0713d217fba5ce8",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/23b37bf3628e6ce3e543ec2dc0713d217fba5ce8",
"gitiles_ref:refs/heads/master"
]
}
[01:12:12.777] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552525931554907",
"id": "8919052284753096832",
"lease_expiration_ts": "1552651932380035",
"lease_key": "396085930",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"23b37bf3628e6ce3e543ec2dc0713d217fba5ce8\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552525932608710",
"tags": [
"builder:clang",
"buildset:commit/git/23b37bf3628e6ce3e543ec2dc0713d217fba5ce8",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/23b37bf3628e6ce3e543ec2dc0713d217fba5ce8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084482346956459120",
"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/8919052284753096832/+/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:4392933dc934b410",
"user_agent:luci-scheduler"
],
"updated_ts": "1552525932608810",
"url": "https://ci.chromium.org/b/8919052284753096832",
"utcnow_ts": "1552525932767298"
}
}
[01:12:12.777] Task URL: https://ci.chromium.org/b/8919052284753096832
[01:12:12.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:2:0) after 1m0s
[01:12:23.395] Received PubSub notification, asking Buildbucket for the build status
[01:12:23.485] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:12.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:2:0)
[01:13:12.751] Timer tick, asking Buildbucket for the build status
[01:13:12.910] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:12.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:4:0) after 1m0s
[01:14:12.985] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:4:0)
[01:14:12.985] Timer tick, asking Buildbucket for the build status
[01:14:13.621] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:13.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:5:0) after 1m0s
[01:15:13.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:5:0)
[01:15:13.617] Timer tick, asking Buildbucket for the build status
[01:15:13.680] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:13.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:6:0) after 1m0s
[01:16:13.690] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:6:0)
[01:16:13.690] Timer tick, asking Buildbucket for the build status
[01:16:13.797] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:13.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:7:0) after 1m0s
[01:17:13.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:7:0)
[01:17:13.794] Timer tick, asking Buildbucket for the build status
[01:17:13.869] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:13.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:8:0) after 1m0s
[01:18:13.868] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:8:0)
[01:18:13.868] Timer tick, asking Buildbucket for the build status
[01:18:13.922] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:13.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:9:0) after 1m0s
[01:19:13.937] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:9:0)
[01:19:13.937] Timer tick, asking Buildbucket for the build status
[01:19:14.044] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:14.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:10:0) after 1m0s
[01:20:14.023] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:10:0)
[01:20:14.023] Timer tick, asking Buildbucket for the build status
[01:20:14.069] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:14.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:11:0) after 1m0s
[01:21:14.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:11:0)
[01:21:14.086] Timer tick, asking Buildbucket for the build status
[01:21:14.213] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:14.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:12:0) after 1m0s
[01:22:14.239] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:12:0)
[01:22:14.239] Timer tick, asking Buildbucket for the build status
[01:22:14.354] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:14.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:13:0) after 1m0s
[01:23:14.366] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:13:0)
[01:23:14.366] Timer tick, asking Buildbucket for the build status
[01:23:14.499] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:14.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:14:0) after 1m0s
[01:24:14.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:14:0)
[01:24:14.523] Timer tick, asking Buildbucket for the build status
[01:24:14.608] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:14.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:15:0) after 1m0s
[01:25:14.624] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:15:0)
[01:25:14.624] Timer tick, asking Buildbucket for the build status
[01:25:14.706] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:14.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:16:0) after 1m0s
[01:26:14.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:16:0)
[01:26:14.736] Timer tick, asking Buildbucket for the build status
[01:26:14.805] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:14.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:17:0) after 1m0s
[01:27:14.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:17:0)
[01:27:14.785] Timer tick, asking Buildbucket for the build status
[01:27:15.050] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:15.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:18:0) after 1m0s
[01:28:15.035] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:18:0)
[01:28:15.035] Timer tick, asking Buildbucket for the build status
[01:28:15.131] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:15.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:19:0) after 1m0s
[01:29:15.154] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:19:0)
[01:29:15.154] Timer tick, asking Buildbucket for the build status
[01:29:15.283] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:15.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:20:0) after 1m0s
[01:30:15.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:20:0)
[01:30:15.297] Timer tick, asking Buildbucket for the build status
[01:30:15.344] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:15.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:21:0) after 1m0s
[01:31:15.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:21:0)
[01:31:15.361] Timer tick, asking Buildbucket for the build status
[01:31:15.542] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:15.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:22:0) after 1m0s
[01:32:15.527] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:22:0)
[01:32:15.527] Timer tick, asking Buildbucket for the build status
[01:32:15.588] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:15.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:23:0) after 1m0s
[01:33:15.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:23:0)
[01:33:15.608] Timer tick, asking Buildbucket for the build status
[01:33:15.685] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:15.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:24:0) after 1m0s
[01:34:15.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:24:0)
[01:34:15.701] Timer tick, asking Buildbucket for the build status
[01:34:15.845] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:15.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:25:0) after 1m0s
[01:35:15.863] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:25:0)
[01:35:15.863] Timer tick, asking Buildbucket for the build status
[01:35:16.043] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:16.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:26:0) after 1m0s
[01:36:16.033] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:26:0)
[01:36:16.033] Timer tick, asking Buildbucket for the build status
[01:36:16.120] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:16.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:27:0) after 1m0s
[01:37:16.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:27:0)
[01:37:16.101] Timer tick, asking Buildbucket for the build status
[01:37:16.168] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:16.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:28:0) after 1m0s
[01:38:16.187] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:28:0)
[01:38:16.187] Timer tick, asking Buildbucket for the build status
[01:38:16.233] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:16.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:29:0) after 1m0s
[01:39:16.252] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:29:0)
[01:39:16.252] Timer tick, asking Buildbucket for the build status
[01:39:16.313] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:16.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:30:0) after 1m0s
[01:40:16.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:30:0)
[01:40:16.308] Timer tick, asking Buildbucket for the build status
[01:40:16.376] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:16.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:31:0) after 1m0s
[01:41:16.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:31:0)
[01:41:16.398] Timer tick, asking Buildbucket for the build status
[01:41:16.497] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:16.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:32:0) after 1m0s
[01:42:16.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:32:0)
[01:42:16.513] Timer tick, asking Buildbucket for the build status
[01:42:16.574] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:16.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:33:0) after 1m0s
[01:43:16.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:33:0)
[01:43:16.578] Timer tick, asking Buildbucket for the build status
[01:43:16.636] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:16.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:34:0) after 1m0s
[01:44:16.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:34:0)
[01:44:16.642] Timer tick, asking Buildbucket for the build status
[01:44:16.710] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:16.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:35:0) after 1m0s
[01:45:16.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:35:0)
[01:45:16.727] Timer tick, asking Buildbucket for the build status
[01:45:16.781] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:16.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:36:0) after 1m0s
[01:46:16.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:36:0)
[01:46:16.796] Timer tick, asking Buildbucket for the build status
[01:46:16.888] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:16.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:37:0) after 1m0s
[01:47:16.931] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:37:0)
[01:47:16.931] Timer tick, asking Buildbucket for the build status
[01:47:17.029] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:17.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:38:0) after 1m0s
[01:48:17.048] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:38:0)
[01:48:17.048] Timer tick, asking Buildbucket for the build status
[01:48:17.093] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:17.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:39:0) after 1m0s
[01:49:17.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:39:0)
[01:49:17.116] Timer tick, asking Buildbucket for the build status
[01:49:17.167] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:17.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:40:0) after 1m0s
[01:50:17.154] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:40:0)
[01:50:17.154] Timer tick, asking Buildbucket for the build status
[01:50:17.215] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:17.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:41:0) after 1m0s
[01:51:17.237] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:41:0)
[01:51:17.237] Timer tick, asking Buildbucket for the build status
[01:51:17.297] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:17.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:42:0) after 1m0s
[01:52:17.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:42:0)
[01:52:17.282] Timer tick, asking Buildbucket for the build status
[01:52:17.382] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:17.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:43:0) after 1m0s
[01:53:17.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:43:0)
[01:53:17.403] Timer tick, asking Buildbucket for the build status
[01:53:17.484] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:17.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:44:0) after 1m0s
[01:54:17.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:44:0)
[01:54:17.464] Timer tick, asking Buildbucket for the build status
[01:54:17.512] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:17.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:45:0) after 1m0s
[01:55:17.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:45:0)
[01:55:17.524] Timer tick, asking Buildbucket for the build status
[01:55:17.649] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:17.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:46:0) after 1m0s
[01:56:17.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:46:0)
[01:56:17.618] Timer tick, asking Buildbucket for the build status
[01:56:17.727] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:17.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:47:0) after 1m0s
[01:57:17.747] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:47:0)
[01:57:17.747] Timer tick, asking Buildbucket for the build status
[01:57:17.812] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:17.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:48:0) after 1m0s
[01:58:17.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:48:0)
[01:58:17.832] Timer tick, asking Buildbucket for the build status
[01:58:17.999] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:17.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:49:0) after 1m0s
[01:59:17.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:49:0)
[01:59:17.992] Timer tick, asking Buildbucket for the build status
[01:59:18.057] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:18.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:50:0) after 1m0s
[02:00:18.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:50:0)
[02:00:18.073] Timer tick, asking Buildbucket for the build status
[02:00:18.407] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:18.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:51:0) after 1m0s
[02:01:18.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:51:0)
[02:01:18.389] Timer tick, asking Buildbucket for the build status
[02:01:18.481] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:18.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:52:0) after 1m0s
[02:02:18.502] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:52:0)
[02:02:18.502] Timer tick, asking Buildbucket for the build status
[02:02:18.614] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:18.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:53:0) after 1m0s
[02:03:18.603] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:53:0)
[02:03:18.603] Timer tick, asking Buildbucket for the build status
[02:03:18.644] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:18.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:54:0) after 1m0s
[02:04:18.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:54:0)
[02:04:18.676] Timer tick, asking Buildbucket for the build status
[02:04:18.859] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:18.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:55:0) after 1m0s
[02:05:18.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:55:0)
[02:05:18.882] Timer tick, asking Buildbucket for the build status
[02:05:18.945] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:18.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:56:0) after 1m0s
[02:06:18.990] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:56:0)
[02:06:18.990] Timer tick, asking Buildbucket for the build status
[02:06:19.051] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:19.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:57:0) after 1m0s
[02:07:19.039] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:57:0)
[02:07:19.039] Timer tick, asking Buildbucket for the build status
[02:07:19.171] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:19.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:58:0) after 1m0s
[02:08:19.161] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:58:0)
[02:08:19.161] Timer tick, asking Buildbucket for the build status
[02:08:19.217] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:19.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:59:0) after 1m0s
[02:09:19.237] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:59:0)
[02:09:19.237] Timer tick, asking Buildbucket for the build status
[02:09:19.489] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:19.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:60:0) after 1m0s
[02:10:19.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:60:0)
[02:10:19.490] Timer tick, asking Buildbucket for the build status
[02:10:19.554] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:19.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:61:0) after 1m0s
[02:11:19.574] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:61:0)
[02:11:19.574] Timer tick, asking Buildbucket for the build status
[02:11:19.618] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:19.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:62:0) after 1m0s
[02:12:19.639] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:62:0)
[02:12:19.639] Timer tick, asking Buildbucket for the build status
[02:12:19.712] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:19.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:63:0) after 1m0s
[02:13:19.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:63:0)
[02:13:19.731] Timer tick, asking Buildbucket for the build status
[02:13:19.814] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:19.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:64:0) after 1m0s
[02:14:19.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:64:0)
[02:14:19.789] Timer tick, asking Buildbucket for the build status
[02:14:19.860] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:19.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:65:0) after 1m0s
[02:15:19.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:65:0)
[02:15:19.883] Timer tick, asking Buildbucket for the build status
[02:15:20.014] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:20.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:66:0) after 1m0s
[02:16:20.076] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:66:0)
[02:16:20.076] Timer tick, asking Buildbucket for the build status
[02:16:20.121] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:20.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:67:0) after 1m0s
[02:17:20.146] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:67:0)
[02:17:20.146] Timer tick, asking Buildbucket for the build status
[02:17:20.295] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:20.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:68:0) after 1m0s
[02:18:20.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:68:0)
[02:18:20.277] Timer tick, asking Buildbucket for the build status
[02:18:20.320] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:20.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:69:0) after 1m0s
[02:19:20.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:69:0)
[02:19:20.348] Timer tick, asking Buildbucket for the build status
[02:19:20.984] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:20.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:70:0) after 1m0s
[02:20:21.018] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:70:0)
[02:20:21.018] Timer tick, asking Buildbucket for the build status
[02:20:21.088] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:21.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:71:0) after 1m0s
[02:21:21.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:71:0)
[02:21:21.092] Timer tick, asking Buildbucket for the build status
[02:21:21.140] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:21.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:72:0) after 1m0s
[02:22:21.137] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:72:0)
[02:22:21.137] Timer tick, asking Buildbucket for the build status
[02:22:21.203] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:21.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:73:0) after 1m0s
[02:23:21.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:73:0)
[02:23:21.209] Timer tick, asking Buildbucket for the build status
[02:23:21.255] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:21.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:74:0) after 1m0s
[02:24:21.258] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:74:0)
[02:24:21.258] Timer tick, asking Buildbucket for the build status
[02:24:21.331] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:21.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:75:0) after 1m0s
[02:25:21.354] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:75:0)
[02:25:21.354] Timer tick, asking Buildbucket for the build status
[02:25:21.772] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:21.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:76:0) after 1m0s
[02:26:21.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:76:0)
[02:26:21.793] Timer tick, asking Buildbucket for the build status
[02:26:22.004] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:22.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:77:0) after 1m0s
[02:27:22.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:77:0)
[02:27:22.037] Timer tick, asking Buildbucket for the build status
[02:27:22.223] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:22.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:78:0) after 1m0s
[02:28:22.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:78:0)
[02:28:22.199] Timer tick, asking Buildbucket for the build status
[02:28:22.310] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:22.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:79:0) after 1m0s
[02:29:22.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:79:0)
[02:29:22.330] Timer tick, asking Buildbucket for the build status
[02:29:22.376] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:22.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:80:0) after 1m0s
[02:30:22.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:80:0)
[02:30:22.358] Timer tick, asking Buildbucket for the build status
[02:30:22.423] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:22.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:81:0) after 1m0s
[02:31:22.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:81:0)
[02:31:22.430] Timer tick, asking Buildbucket for the build status
[02:31:22.485] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:22.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:82:0) after 1m0s
[02:32:22.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:82:0)
[02:32:22.481] Timer tick, asking Buildbucket for the build status
[02:32:22.595] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:22.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:83:0) after 1m0s
[02:33:22.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:83:0)
[02:33:22.584] Timer tick, asking Buildbucket for the build status
[02:33:22.634] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:22.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:84:0) after 1m0s
[02:34:22.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:84:0)
[02:34:22.654] Timer tick, asking Buildbucket for the build status
[02:34:22.924] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:22.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:85:0) after 1m0s
[02:35:22.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:85:0)
[02:35:22.923] Timer tick, asking Buildbucket for the build status
[02:35:23.007] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:23.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:86:0) after 1m0s
[02:36:22.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:86:0)
[02:36:22.999] Timer tick, asking Buildbucket for the build status
[02:36:23.118] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:23.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:87:0) after 1m0s
[02:37:23.140] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:87:0)
[02:37:23.140] Timer tick, asking Buildbucket for the build status
[02:37:23.192] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:23.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:88:0) after 1m0s
[02:38:23.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:88:0)
[02:38:23.212] Timer tick, asking Buildbucket for the build status
[02:38:23.335] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:23.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:89:0) after 1m0s
[02:39:23.357] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:89:0)
[02:39:23.357] Timer tick, asking Buildbucket for the build status
[02:39:23.662] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:23.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:90:0) after 1m0s
[02:40:23.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:90:0)
[02:40:23.686] Timer tick, asking Buildbucket for the build status
[02:40:23.967] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:23.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:91:0) after 1m0s
[02:41:24.030] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:91:0)
[02:41:24.030] Timer tick, asking Buildbucket for the build status
[02:41:24.089] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:24.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:92:0) after 1m0s
[02:42:24.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:92:0)
[02:42:24.095] Timer tick, asking Buildbucket for the build status
[02:42:24.260] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:24.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:93:0) after 1m0s
[02:43:24.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:93:0)
[02:43:24.283] Timer tick, asking Buildbucket for the build status
[02:43:24.338] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:24.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:94:0) after 1m0s
[02:44:24.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:94:0)
[02:44:24.358] Timer tick, asking Buildbucket for the build status
[02:44:24.439] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:24.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:95:0) after 1m0s
[02:45:24.435] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:95:0)
[02:45:24.435] Timer tick, asking Buildbucket for the build status
[02:45:24.576] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:24.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:96:0) after 1m0s
[02:46:24.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:96:0)
[02:46:24.598] Timer tick, asking Buildbucket for the build status
[02:46:24.665] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:24.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:97:0) after 1m0s
[02:47:24.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:97:0)
[02:47:24.684] Timer tick, asking Buildbucket for the build status
[02:47:24.730] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:24.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:98:0) after 1m0s
[02:48:24.751] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:98:0)
[02:48:24.751] Timer tick, asking Buildbucket for the build status
[02:48:24.916] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:24.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:99:0) after 1m0s
[02:49:24.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:99:0)
[02:49:24.945] Timer tick, asking Buildbucket for the build status
[02:49:25.047] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:25.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:100:0) after 1m0s
[02:50:25.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:100:0)
[02:50:25.095] Timer tick, asking Buildbucket for the build status
[02:50:25.289] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:25.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:101:0) after 1m0s
[02:51:25.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:101:0)
[02:51:25.297] Timer tick, asking Buildbucket for the build status
[02:51:25.378] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:25.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:102:0) after 1m0s
[02:52:25.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:102:0)
[02:52:25.383] Timer tick, asking Buildbucket for the build status
[02:52:25.451] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:25.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:103:0) after 1m0s
[02:53:25.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:103:0)
[02:53:25.460] Timer tick, asking Buildbucket for the build status
[02:53:25.509] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:25.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:104:0) after 1m0s
[02:54:25.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:104:0)
[02:54:25.513] Timer tick, asking Buildbucket for the build status
[02:54:25.584] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:25.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:105:0) after 1m0s
[02:55:25.623] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:105:0)
[02:55:25.623] Timer tick, asking Buildbucket for the build status
[02:55:25.663] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:25.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:106:0) after 1m0s
[02:56:25.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:106:0)
[02:56:25.686] Timer tick, asking Buildbucket for the build status
[02:56:25.765] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:25.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:107:0) after 1m0s
[02:57:25.829] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:107:0)
[02:57:25.829] Timer tick, asking Buildbucket for the build status
[02:57:25.897] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:25.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:108:0) after 1m0s
[02:58:25.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:108:0)
[02:58:25.882] Timer tick, asking Buildbucket for the build status
[02:58:26.063] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:26.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:109:0) after 1m0s
[02:59:26.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:109:0)
[02:59:26.068] Timer tick, asking Buildbucket for the build status
[02:59:26.205] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:26.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:110:0) after 1m0s
[03:00:26.227] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:110:0)
[03:00:26.227] Timer tick, asking Buildbucket for the build status
[03:00:26.363] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:26.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:111:0) after 1m0s
[03:01:26.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:111:0)
[03:01:26.387] Timer tick, asking Buildbucket for the build status
[03:01:26.435] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:26.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:112:0) after 1m0s
[03:02:26.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:112:0)
[03:02:26.436] Timer tick, asking Buildbucket for the build status
[03:02:26.663] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:26.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:113:0) after 1m0s
[03:03:26.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:113:0)
[03:03:26.678] Timer tick, asking Buildbucket for the build status
[03:03:26.782] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:26.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:114:0) after 1m0s
[03:04:26.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:114:0)
[03:04:26.760] Timer tick, asking Buildbucket for the build status
[03:04:26.831] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:26.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:115:0) after 1m0s
[03:05:26.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:115:0)
[03:05:26.854] Timer tick, asking Buildbucket for the build status
[03:05:26.899] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:26.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:116:0) after 1m0s
[03:06:26.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:116:0)
[03:06:26.927] Timer tick, asking Buildbucket for the build status
[03:06:27.095] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:27.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:117:0) after 1m0s
[03:07:27.119] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:117:0)
[03:07:27.119] Timer tick, asking Buildbucket for the build status
[03:07:27.170] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:27.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:118:0) after 1m0s
[03:08:27.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:118:0)
[03:08:27.192] Timer tick, asking Buildbucket for the build status
[03:08:27.279] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:27.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:119:0) after 1m0s
[03:09:27.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:119:0)
[03:09:27.269] Timer tick, asking Buildbucket for the build status
[03:09:27.325] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:27.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:120:0) after 1m0s
[03:10:27.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:120:0)
[03:10:27.321] Timer tick, asking Buildbucket for the build status
[03:10:27.661] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:27.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:121:0) after 1m0s
[03:11:27.680] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:121:0)
[03:11:27.680] Timer tick, asking Buildbucket for the build status
[03:11:27.732] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:27.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:122:0) after 1m0s
[03:12:27.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:122:0)
[03:12:27.754] Timer tick, asking Buildbucket for the build status
[03:12:27.815] Build 8919052284753096832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:27.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084482346956459120:123:0) after 1m0s
[03:12:55.351] Received PubSub notification, asking Buildbucket for the build status
[03:12:55.406] Build 8919052284753096832: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:12:55.406] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1552533173894354",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552525931554907",
"failure_reason": "BUILD_FAILURE",
"id": "8919052284753096832",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"23b37bf3628e6ce3e543ec2dc0713d217fba5ce8\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"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\": [\"4186-af94c3f\"], \"machine_type\": [\"n1-standard-1\"], \"gpu\": [\"none\"], \"cores\": [\"1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"id\": [\"fuchsia-debian-1-03c1d9de-us-central1-b-qll7\"], \"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\"}, \"timeout\": 7200.0, \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"buildername\": \"clang\", \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"recipe\": \"buildbucket_trigger\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"bot_id\": \"fuchsia-debian-1-03c1d9de-us-central1-b-qll7\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"revision\": \"23b37bf3628e6ce3e543ec2dc0713d217fba5ce8\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1552525942769614",
"status": "COMPLETED",
"status_changed_ts": "1552533175009535",
"tags": [
"builder:clang",
"buildset:commit/git/23b37bf3628e6ce3e543ec2dc0713d217fba5ce8",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/23b37bf3628e6ce3e543ec2dc0713d217fba5ce8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084482346956459120",
"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/8919052284753096832/+/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:4392933dc934b410",
"user_agent:luci-scheduler"
],
"updated_ts": "1552533175021702",
"url": "https://ci.chromium.org/b/8919052284753096832",
"utcnow_ts": "1552533175395542"
}
}
[03:12:55.406] Invocation finished in 2h0m45.337210955s with status FAILED