[19:25:42.277] New invocation is queued and will start shortly
[19:25:43.362] Starting the invocation (attempt 1)
[19:25:43.396] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:25:43.396] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:25:43.396] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9084232353654480976",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"3b0a6c69ee54fbe3a873adca35fa9b282121fb3d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9084232353654480976",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/3b0a6c69ee54fbe3a873adca35fa9b282121fb3d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3b0a6c69ee54fbe3a873adca35fa9b282121fb3d",
"gitiles_ref:refs/heads/master"
]
}
[19:25:44.836] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1552764343527331",
"id": "8918802291680494688",
"lease_expiration_ts": "1552890344550065",
"lease_key": "1202192352",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"3b0a6c69ee54fbe3a873adca35fa9b282121fb3d\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552764344748478",
"tags": [
"builder:clang",
"buildset:commit/git/3b0a6c69ee54fbe3a873adca35fa9b282121fb3d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3b0a6c69ee54fbe3a873adca35fa9b282121fb3d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084232353654480976",
"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/8918802291680494688/+/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:43a0c91fea944810",
"user_agent:luci-scheduler"
],
"updated_ts": "1552764344748581",
"url": "https://ci.chromium.org/b/8918802291680494688",
"utcnow_ts": "1552764344828245"
}
}
[19:25:44.836] Task URL: https://ci.chromium.org/b/8918802291680494688
[19:25:44.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:2:0) after 1m0s
[19:26:03.603] Received PubSub notification, asking Buildbucket for the build status
[19:26:03.659] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:44.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:2:0)
[19:26:44.853] Timer tick, asking Buildbucket for the build status
[19:26:44.925] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:44.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:4:0) after 1m0s
[19:27:44.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:4:0)
[19:27:44.942] Timer tick, asking Buildbucket for the build status
[19:27:45.032] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:45.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:5:0) after 1m0s
[19:28:45.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:5:0)
[19:28:45.075] Timer tick, asking Buildbucket for the build status
[19:28:45.122] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:45.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:6:0) after 1m0s
[19:29:45.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:6:0)
[19:29:45.145] Timer tick, asking Buildbucket for the build status
[19:29:45.210] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:45.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:7:0) after 1m0s
[19:30:45.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:7:0)
[19:30:45.231] Timer tick, asking Buildbucket for the build status
[19:30:45.366] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:45.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:8:0) after 1m0s
[19:31:45.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:8:0)
[19:31:45.375] Timer tick, asking Buildbucket for the build status
[19:31:45.446] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:45.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:9:0) after 1m0s
[19:32:45.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:9:0)
[19:32:45.463] Timer tick, asking Buildbucket for the build status
[19:32:45.522] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:45.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:10:0) after 1m0s
[19:33:45.547] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:10:0)
[19:33:45.547] Timer tick, asking Buildbucket for the build status
[19:33:45.661] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:45.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:11:0) after 1m0s
[19:34:45.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:11:0)
[19:34:45.650] Timer tick, asking Buildbucket for the build status
[19:34:45.711] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:45.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:12:0) after 1m0s
[19:35:45.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:12:0)
[19:35:45.730] Timer tick, asking Buildbucket for the build status
[19:35:45.805] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:45.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:13:0) after 1m0s
[19:36:45.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:13:0)
[19:36:45.775] Timer tick, asking Buildbucket for the build status
[19:36:45.893] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:45.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:14:0) after 1m0s
[19:37:45.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:14:0)
[19:37:45.878] Timer tick, asking Buildbucket for the build status
[19:37:45.939] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:45.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:15:0) after 1m0s
[19:38:45.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:15:0)
[19:38:45.942] Timer tick, asking Buildbucket for the build status
[19:38:46.002] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:46.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:16:0) after 1m0s
[19:39:45.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:16:0)
[19:39:45.999] Timer tick, asking Buildbucket for the build status
[19:39:46.179] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:46.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:17:0) after 1m0s
[19:40:46.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:17:0)
[19:40:46.199] Timer tick, asking Buildbucket for the build status
[19:40:46.370] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:46.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:18:0) after 1m0s
[19:41:46.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:18:0)
[19:41:46.389] Timer tick, asking Buildbucket for the build status
[19:41:46.482] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:46.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:19:0) after 1m0s
[19:42:46.455] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:19:0)
[19:42:46.455] Timer tick, asking Buildbucket for the build status
[19:42:46.502] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:46.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:20:0) after 1m0s
[19:43:46.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:20:0)
[19:43:46.522] Timer tick, asking Buildbucket for the build status
[19:43:46.694] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:46.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:21:0) after 1m0s
[19:44:46.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:21:0)
[19:44:46.712] Timer tick, asking Buildbucket for the build status
[19:44:46.815] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:46.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:22:0) after 1m0s
[19:45:46.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:22:0)
[19:45:46.833] Timer tick, asking Buildbucket for the build status
[19:45:47.088] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:47.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:23:0) after 1m0s
[19:46:47.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:23:0)
[19:46:47.072] Timer tick, asking Buildbucket for the build status
[19:46:47.367] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:47.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:24:0) after 1m0s
[19:47:47.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:24:0)
[19:47:47.340] Timer tick, asking Buildbucket for the build status
[19:47:47.423] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:47.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:25:0) after 1m0s
[19:48:47.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:25:0)
[19:48:47.479] Timer tick, asking Buildbucket for the build status
[19:48:47.577] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:47.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:26:0) after 1m0s
[19:49:47.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:26:0)
[19:49:47.610] Timer tick, asking Buildbucket for the build status
[19:49:47.673] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:47.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:27:0) after 1m0s
[19:50:47.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:27:0)
[19:50:47.718] Timer tick, asking Buildbucket for the build status
[19:50:47.779] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:47.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:28:0) after 1m0s
[19:51:47.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:28:0)
[19:51:47.790] Timer tick, asking Buildbucket for the build status
[19:51:47.863] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:47.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:29:0) after 1m0s
[19:52:47.893] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:29:0)
[19:52:47.893] Timer tick, asking Buildbucket for the build status
[19:52:47.965] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:47.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:30:0) after 1m0s
[19:53:47.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:30:0)
[19:53:47.984] Timer tick, asking Buildbucket for the build status
[19:53:48.100] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:48.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:31:0) after 1m0s
[19:54:48.080] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:31:0)
[19:54:48.080] Timer tick, asking Buildbucket for the build status
[19:54:48.377] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:48.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:32:0) after 1m0s
[19:55:48.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:32:0)
[19:55:48.351] Timer tick, asking Buildbucket for the build status
[19:55:48.474] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:48.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:33:0) after 1m0s
[19:56:48.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:33:0)
[19:56:48.482] Timer tick, asking Buildbucket for the build status
[19:56:48.667] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:48.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:34:0) after 1m0s
[19:57:48.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:34:0)
[19:57:48.676] Timer tick, asking Buildbucket for the build status
[19:57:48.730] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:48.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:35:0) after 1m0s
[19:58:48.748] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:35:0)
[19:58:48.748] Timer tick, asking Buildbucket for the build status
[19:58:48.803] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:48.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:36:0) after 1m0s
[19:59:48.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:36:0)
[19:59:48.791] Timer tick, asking Buildbucket for the build status
[19:59:48.980] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:48.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:37:0) after 1m0s
[20:00:48.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:37:0)
[20:00:48.980] Timer tick, asking Buildbucket for the build status
[20:00:49.092] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:49.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:38:0) after 1m0s
[20:01:49.078] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:38:0)
[20:01:49.078] Timer tick, asking Buildbucket for the build status
[20:01:49.214] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:49.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:39:0) after 1m0s
[20:02:49.232] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:39:0)
[20:02:49.232] Timer tick, asking Buildbucket for the build status
[20:02:49.297] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:49.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:40:0) after 1m0s
[20:03:49.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:40:0)
[20:03:49.304] Timer tick, asking Buildbucket for the build status
[20:03:49.371] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:49.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:41:0) after 1m0s
[20:04:49.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:41:0)
[20:04:49.391] Timer tick, asking Buildbucket for the build status
[20:04:49.476] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:49.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:42:0) after 1m0s
[20:05:49.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:42:0)
[20:05:49.464] Timer tick, asking Buildbucket for the build status
[20:05:49.520] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:49.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:43:0) after 1m0s
[20:06:49.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:43:0)
[20:06:49.553] Timer tick, asking Buildbucket for the build status
[20:06:49.614] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:49.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:44:0) after 1m0s
[20:07:49.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:44:0)
[20:07:49.616] Timer tick, asking Buildbucket for the build status
[20:07:49.683] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:49.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:45:0) after 1m0s
[20:08:49.657] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:45:0)
[20:08:49.657] Timer tick, asking Buildbucket for the build status
[20:08:49.718] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:49.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:46:0) after 1m0s
[20:09:49.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:46:0)
[20:09:49.778] Timer tick, asking Buildbucket for the build status
[20:09:49.969] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:49.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:47:0) after 1m0s
[20:10:49.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:47:0)
[20:10:49.949] Timer tick, asking Buildbucket for the build status
[20:10:50.017] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:50.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:48:0) after 1m0s
[20:11:50.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:48:0)
[20:11:50.013] Timer tick, asking Buildbucket for the build status
[20:11:50.073] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:50.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:49:0) after 1m0s
[20:12:50.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:49:0)
[20:12:50.088] Timer tick, asking Buildbucket for the build status
[20:12:50.208] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:50.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:50:0) after 1m0s
[20:13:50.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:50:0)
[20:13:50.224] Timer tick, asking Buildbucket for the build status
[20:13:50.288] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:50.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:51:0) after 1m0s
[20:14:50.262] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:51:0)
[20:14:50.262] Timer tick, asking Buildbucket for the build status
[20:14:50.319] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:50.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:52:0) after 1m0s
[20:15:50.341] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:52:0)
[20:15:50.341] Timer tick, asking Buildbucket for the build status
[20:15:50.411] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:50.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:53:0) after 1m0s
[20:16:50.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:53:0)
[20:16:50.429] Timer tick, asking Buildbucket for the build status
[20:16:50.534] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:50.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:54:0) after 1m0s
[20:17:50.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:54:0)
[20:17:50.539] Timer tick, asking Buildbucket for the build status
[20:17:50.604] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:50.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:55:0) after 1m0s
[20:18:50.624] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:55:0)
[20:18:50.624] Timer tick, asking Buildbucket for the build status
[20:18:50.680] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:50.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:56:0) after 1m0s
[20:19:50.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:56:0)
[20:19:50.701] Timer tick, asking Buildbucket for the build status
[20:19:50.778] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:50.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:57:0) after 1m0s
[20:20:50.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:57:0)
[20:20:50.791] Timer tick, asking Buildbucket for the build status
[20:20:50.854] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:50.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:58:0) after 1m0s
[20:21:50.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:58:0)
[20:21:50.884] Timer tick, asking Buildbucket for the build status
[20:21:51.281] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:51.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:59:0) after 1m0s
[20:22:51.272] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:59:0)
[20:22:51.272] Timer tick, asking Buildbucket for the build status
[20:22:51.325] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:51.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:60:0) after 1m0s
[20:23:51.345] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:60:0)
[20:23:51.345] Timer tick, asking Buildbucket for the build status
[20:23:51.692] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:51.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:61:0) after 1m0s
[20:24:51.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:61:0)
[20:24:51.709] Timer tick, asking Buildbucket for the build status
[20:24:51.766] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:51.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:62:0) after 1m0s
[20:25:51.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:62:0)
[20:25:51.767] Timer tick, asking Buildbucket for the build status
[20:25:51.844] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:51.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:63:0) after 1m0s
[20:26:51.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:63:0)
[20:26:51.854] Timer tick, asking Buildbucket for the build status
[20:26:52.081] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:52.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:64:0) after 1m0s
[20:27:52.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:64:0)
[20:27:52.095] Timer tick, asking Buildbucket for the build status
[20:27:52.159] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:52.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:65:0) after 1m0s
[20:28:52.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:65:0)
[20:28:52.153] Timer tick, asking Buildbucket for the build status
[20:28:52.280] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:52.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:66:0) after 1m0s
[20:29:52.267] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:66:0)
[20:29:52.267] Timer tick, asking Buildbucket for the build status
[20:29:52.481] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:52.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:67:0) after 1m0s
[20:30:52.500] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:67:0)
[20:30:52.500] Timer tick, asking Buildbucket for the build status
[20:30:52.595] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:52.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:68:0) after 1m0s
[20:31:52.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:68:0)
[20:31:52.650] Timer tick, asking Buildbucket for the build status
[20:31:52.913] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:52.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:69:0) after 1m0s
[20:32:52.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:69:0)
[20:32:52.933] Timer tick, asking Buildbucket for the build status
[20:32:52.991] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:52.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:70:0) after 1m0s
[20:33:53.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:70:0)
[20:33:53.009] Timer tick, asking Buildbucket for the build status
[20:33:53.136] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:53.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:71:0) after 1m0s
[20:34:53.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:71:0)
[20:34:53.156] Timer tick, asking Buildbucket for the build status
[20:34:53.216] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:53.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:72:0) after 1m0s
[20:35:53.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:72:0)
[20:35:53.238] Timer tick, asking Buildbucket for the build status
[20:35:53.561] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:53.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:73:0) after 1m0s
[20:36:53.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:73:0)
[20:36:53.580] Timer tick, asking Buildbucket for the build status
[20:36:53.640] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:53.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:74:0) after 1m0s
[20:37:53.653] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:74:0)
[20:37:53.653] Timer tick, asking Buildbucket for the build status
[20:37:53.883] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:53.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:75:0) after 1m0s
[20:38:53.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:75:0)
[20:38:53.907] Timer tick, asking Buildbucket for the build status
[20:38:53.971] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:53.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:76:0) after 1m0s
[20:39:53.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:76:0)
[20:39:53.964] Timer tick, asking Buildbucket for the build status
[20:39:54.032] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:54.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:77:0) after 1m0s
[20:40:54.054] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:77:0)
[20:40:54.054] Timer tick, asking Buildbucket for the build status
[20:40:54.109] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:54.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:78:0) after 1m0s
[20:41:54.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:78:0)
[20:41:54.133] Timer tick, asking Buildbucket for the build status
[20:41:54.200] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:54.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:79:0) after 1m0s
[20:42:54.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:79:0)
[20:42:54.213] Timer tick, asking Buildbucket for the build status
[20:42:54.276] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:54.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:80:0) after 1m0s
[20:43:54.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:80:0)
[20:43:54.414] Timer tick, asking Buildbucket for the build status
[20:43:54.478] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:54.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:81:0) after 1m0s
[20:44:54.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:81:0)
[20:44:54.483] Timer tick, asking Buildbucket for the build status
[20:44:54.563] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:54.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:82:0) after 1m0s
[20:45:54.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:82:0)
[20:45:54.582] Timer tick, asking Buildbucket for the build status
[20:45:54.712] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:54.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:83:0) after 1m0s
[20:46:54.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:83:0)
[20:46:54.722] Timer tick, asking Buildbucket for the build status
[20:46:54.879] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:54.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:84:0) after 1m0s
[20:47:54.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:84:0)
[20:47:54.897] Timer tick, asking Buildbucket for the build status
[20:47:55.266] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:55.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:85:0) after 1m0s
[20:48:55.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:85:0)
[20:48:55.269] Timer tick, asking Buildbucket for the build status
[20:48:55.385] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:55.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:86:0) after 1m0s
[20:49:55.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:86:0)
[20:49:55.406] Timer tick, asking Buildbucket for the build status
[20:49:55.664] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:55.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:87:0) after 1m0s
[20:50:55.723] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:87:0)
[20:50:55.723] Timer tick, asking Buildbucket for the build status
[20:50:55.981] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:55.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:88:0) after 1m0s
[20:51:55.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:88:0)
[20:51:55.996] Timer tick, asking Buildbucket for the build status
[20:51:56.171] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:56.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:89:0) after 1m0s
[20:52:56.195] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:89:0)
[20:52:56.195] Timer tick, asking Buildbucket for the build status
[20:52:56.292] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:56.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:90:0) after 1m0s
[20:53:56.324] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:90:0)
[20:53:56.324] Timer tick, asking Buildbucket for the build status
[20:53:56.387] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:56.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:91:0) after 1m0s
[20:54:56.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:91:0)
[20:54:56.404] Timer tick, asking Buildbucket for the build status
[20:54:56.463] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:56.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:92:0) after 1m0s
[20:55:56.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:92:0)
[20:55:56.489] Timer tick, asking Buildbucket for the build status
[20:55:56.545] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:56.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:93:0) after 1m0s
[20:56:56.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:93:0)
[20:56:56.531] Timer tick, asking Buildbucket for the build status
[20:56:56.595] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:56.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:94:0) after 1m0s
[20:57:56.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:94:0)
[20:57:56.569] Timer tick, asking Buildbucket for the build status
[20:57:56.777] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:56.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:95:0) after 1m0s
[20:58:56.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:95:0)
[20:58:56.871] Timer tick, asking Buildbucket for the build status
[20:58:56.943] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:56.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:96:0) after 1m0s
[20:59:56.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:96:0)
[20:59:56.936] Timer tick, asking Buildbucket for the build status
[20:59:57.186] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:57.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:97:0) after 1m0s
[21:00:57.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:97:0)
[21:00:57.210] Timer tick, asking Buildbucket for the build status
[21:00:57.296] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:57.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:98:0) after 1m0s
[21:01:57.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:98:0)
[21:01:57.292] Timer tick, asking Buildbucket for the build status
[21:01:57.457] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:57.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:99:0) after 1m0s
[21:02:57.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:99:0)
[21:02:57.477] Timer tick, asking Buildbucket for the build status
[21:02:57.675] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:57.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:100:0) after 1m0s
[21:03:57.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:100:0)
[21:03:57.698] Timer tick, asking Buildbucket for the build status
[21:03:57.810] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:57.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:101:0) after 1m0s
[21:04:57.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:101:0)
[21:04:57.807] Timer tick, asking Buildbucket for the build status
[21:04:58.067] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:58.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:102:0) after 1m0s
[21:05:58.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:102:0)
[21:05:58.106] Timer tick, asking Buildbucket for the build status
[21:05:58.171] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:58.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:103:0) after 1m0s
[21:06:58.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:103:0)
[21:06:58.196] Timer tick, asking Buildbucket for the build status
[21:06:58.328] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:58.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:104:0) after 1m0s
[21:07:58.349] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:104:0)
[21:07:58.349] Timer tick, asking Buildbucket for the build status
[21:07:58.508] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:58.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:105:0) after 1m0s
[21:08:58.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:105:0)
[21:08:58.506] Timer tick, asking Buildbucket for the build status
[21:08:58.569] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:58.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:106:0) after 1m0s
[21:09:58.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:106:0)
[21:09:58.601] Timer tick, asking Buildbucket for the build status
[21:09:58.663] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:58.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:107:0) after 1m0s
[21:10:58.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:107:0)
[21:10:58.682] Timer tick, asking Buildbucket for the build status
[21:10:58.758] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:58.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:108:0) after 1m0s
[21:11:58.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:108:0)
[21:11:58.780] Timer tick, asking Buildbucket for the build status
[21:11:58.840] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:58.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:109:0) after 1m0s
[21:12:58.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:109:0)
[21:12:58.862] Timer tick, asking Buildbucket for the build status
[21:12:58.970] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:58.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:110:0) after 1m0s
[21:13:58.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:110:0)
[21:13:58.964] Timer tick, asking Buildbucket for the build status
[21:13:59.038] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:59.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:111:0) after 1m0s
[21:14:59.038] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:111:0)
[21:14:59.038] Timer tick, asking Buildbucket for the build status
[21:14:59.169] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:59.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:112:0) after 1m0s
[21:15:59.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:112:0)
[21:15:59.202] Timer tick, asking Buildbucket for the build status
[21:15:59.265] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:59.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:113:0) after 1m0s
[21:16:59.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:113:0)
[21:16:59.280] Timer tick, asking Buildbucket for the build status
[21:16:59.453] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:59.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:114:0) after 1m0s
[21:17:59.473] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:114:0)
[21:17:59.473] Timer tick, asking Buildbucket for the build status
[21:17:59.701] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:59.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:115:0) after 1m0s
[21:18:59.708] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:115:0)
[21:18:59.708] Timer tick, asking Buildbucket for the build status
[21:18:59.888] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:59.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:116:0) after 1m0s
[21:19:59.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:116:0)
[21:19:59.923] Timer tick, asking Buildbucket for the build status
[21:20:00.168] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:00.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:117:0) after 1m0s
[21:21:00.184] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:117:0)
[21:21:00.184] Timer tick, asking Buildbucket for the build status
[21:21:00.351] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:00.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:118:0) after 1m0s
[21:22:00.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:118:0)
[21:22:00.346] Timer tick, asking Buildbucket for the build status
[21:22:00.593] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:00.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:119:0) after 1m0s
[21:23:00.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:119:0)
[21:23:00.583] Timer tick, asking Buildbucket for the build status
[21:23:00.667] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:00.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:120:0) after 1m0s
[21:24:00.700] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:120:0)
[21:24:00.700] Timer tick, asking Buildbucket for the build status
[21:24:01.012] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:01.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:121:0) after 1m0s
[21:25:01.032] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:121:0)
[21:25:01.032] Timer tick, asking Buildbucket for the build status
[21:25:01.381] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:01.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:122:0) after 1m0s
[21:26:01.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:122:0)
[21:26:01.390] Timer tick, asking Buildbucket for the build status
[21:26:01.453] Build 8918802291680494688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:01.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084232353654480976:123:0) after 1m0s
[21:26:37.530] Received PubSub notification, asking Buildbucket for the build status
[21:26:37.568] Build 8918802291680494688: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:26:37.568] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1552771596773098",
"created_by": "project:fuchsia",
"created_ts": "1552764343527331",
"failure_reason": "BUILD_FAILURE",
"id": "8918802291680494688",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"3b0a6c69ee54fbe3a873adca35fa9b282121fb3d\"}}",
"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-3rg8\"], \"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-c-3rg8\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"revision\": \"3b0a6c69ee54fbe3a873adca35fa9b282121fb3d\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1552764362966571",
"status": "COMPLETED",
"status_changed_ts": "1552771597248661",
"tags": [
"builder:clang",
"buildset:commit/git/3b0a6c69ee54fbe3a873adca35fa9b282121fb3d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3b0a6c69ee54fbe3a873adca35fa9b282121fb3d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084232353654480976",
"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/8918802291680494688/+/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:43a0c91fea944810",
"user_agent:luci-scheduler"
],
"updated_ts": "1552771597252286",
"url": "https://ci.chromium.org/b/8918802291680494688",
"utcnow_ts": "1552771597558840"
}
}
[21:26:37.568] Invocation finished in 2h0m55.306588069s with status FAILED