[15:33:52.073] New invocation is queued and will start shortly
[15:33:53.207] Starting the invocation (attempt 1)
[15:33:53.254] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:33:53.254] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:33:53.254] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9085062312257522944",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"a5ecceed1fbf512fbde09b30dc59f793547c4981\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9085062312257522944",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/a5ecceed1fbf512fbde09b30dc59f793547c4981",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a5ecceed1fbf512fbde09b30dc59f793547c4981",
"gitiles_ref:refs/heads/master"
]
}
[15:33:55.467] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551972833472993",
"id": "8919632250127953504",
"lease_expiration_ts": "1552098834751468",
"lease_key": "2097017033",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a5ecceed1fbf512fbde09b30dc59f793547c4981\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551972834964581",
"tags": [
"builder:clang",
"buildset:commit/git/a5ecceed1fbf512fbde09b30dc59f793547c4981",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a5ecceed1fbf512fbde09b30dc59f793547c4981",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085062312257522944",
"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/8919632250127953504/+/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:43719ba44ddb8610",
"user_agent:luci-scheduler"
],
"updated_ts": "1551972834964682",
"url": "https://ci.chromium.org/b/8919632250127953504",
"utcnow_ts": "1551972835451529"
}
}
[15:33:55.467] Task URL: https://ci.chromium.org/b/8919632250127953504
[15:33:55.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:2:0) after 1m0s
[15:34:09.508] Received PubSub notification, asking Buildbucket for the build status
[15:34:09.688] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:55.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:2:0)
[15:34:55.478] Timer tick, asking Buildbucket for the build status
[15:34:55.653] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:55.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:4:0) after 1m0s
[15:35:55.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:4:0)
[15:35:55.669] Timer tick, asking Buildbucket for the build status
[15:35:55.806] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:55.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:5:0) after 1m0s
[15:36:55.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:5:0)
[15:36:55.826] Timer tick, asking Buildbucket for the build status
[15:36:55.869] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:55.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:6:0) after 1m0s
[15:37:55.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:6:0)
[15:37:55.853] Timer tick, asking Buildbucket for the build status
[15:37:55.914] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:55.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:7:0) after 1m0s
[15:38:55.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:7:0)
[15:38:55.930] Timer tick, asking Buildbucket for the build status
[15:38:56.031] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:56.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:8:0) after 1m0s
[15:39:56.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:8:0)
[15:39:56.034] Timer tick, asking Buildbucket for the build status
[15:39:56.085] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:56.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:9:0) after 1m0s
[15:40:56.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:9:0)
[15:40:56.102] Timer tick, asking Buildbucket for the build status
[15:40:56.208] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:56.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:10:0) after 1m0s
[15:41:56.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:10:0)
[15:41:56.181] Timer tick, asking Buildbucket for the build status
[15:41:56.479] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:56.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:11:0) after 1m0s
[15:42:56.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:11:0)
[15:42:56.503] Timer tick, asking Buildbucket for the build status
[15:42:56.549] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:56.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:12:0) after 1m0s
[15:43:56.570] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:12:0)
[15:43:56.570] Timer tick, asking Buildbucket for the build status
[15:43:56.639] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:56.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:13:0) after 1m0s
[15:44:56.667] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:13:0)
[15:44:56.667] Timer tick, asking Buildbucket for the build status
[15:44:56.709] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:56.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:14:0) after 1m0s
[15:45:56.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:14:0)
[15:45:56.677] Timer tick, asking Buildbucket for the build status
[15:45:56.733] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:56.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:15:0) after 1m0s
[15:46:56.762] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:15:0)
[15:46:56.762] Timer tick, asking Buildbucket for the build status
[15:46:56.913] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:56.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:16:0) after 1m0s
[15:47:56.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:16:0)
[15:47:56.913] Timer tick, asking Buildbucket for the build status
[15:47:56.986] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:56.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:17:0) after 1m0s
[15:48:56.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:17:0)
[15:48:56.993] Timer tick, asking Buildbucket for the build status
[15:48:57.037] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:57.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:18:0) after 1m0s
[15:49:57.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:18:0)
[15:49:57.063] Timer tick, asking Buildbucket for the build status
[15:49:57.512] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:57.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:19:0) after 1m0s
[15:50:57.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:19:0)
[15:50:57.486] Timer tick, asking Buildbucket for the build status
[15:50:57.552] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:57.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:20:0) after 1m0s
[15:51:57.586] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:20:0)
[15:51:57.586] Timer tick, asking Buildbucket for the build status
[15:51:57.640] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:57.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:21:0) after 1m0s
[15:52:57.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:21:0)
[15:52:57.615] Timer tick, asking Buildbucket for the build status
[15:52:57.660] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:57.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:22:0) after 1m0s
[15:53:57.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:22:0)
[15:53:57.678] Timer tick, asking Buildbucket for the build status
[15:53:57.795] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:57.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:23:0) after 1m0s
[15:54:57.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:23:0)
[15:54:57.786] Timer tick, asking Buildbucket for the build status
[15:54:57.829] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:57.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:24:0) after 1m0s
[15:55:57.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:24:0)
[15:55:57.827] Timer tick, asking Buildbucket for the build status
[15:55:57.943] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:57.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:25:0) after 1m0s
[15:56:57.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:25:0)
[15:56:57.964] Timer tick, asking Buildbucket for the build status
[15:56:58.015] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:58.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:26:0) after 1m0s
[15:57:58.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:26:0)
[15:57:58.015] Timer tick, asking Buildbucket for the build status
[15:57:58.083] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:58.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:27:0) after 1m0s
[15:58:58.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:27:0)
[15:58:58.101] Timer tick, asking Buildbucket for the build status
[15:58:58.361] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:58.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:28:0) after 1m0s
[15:59:58.366] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:28:0)
[15:59:58.366] Timer tick, asking Buildbucket for the build status
[15:59:58.465] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:58.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:29:0) after 1m0s
[16:00:58.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:29:0)
[16:00:58.464] Timer tick, asking Buildbucket for the build status
[16:00:58.532] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:58.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:30:0) after 1m0s
[16:01:58.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:30:0)
[16:01:58.524] Timer tick, asking Buildbucket for the build status
[16:01:58.662] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:58.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:31:0) after 1m0s
[16:02:58.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:31:0)
[16:02:58.675] Timer tick, asking Buildbucket for the build status
[16:02:58.850] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:58.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:32:0) after 1m0s
[16:03:58.824] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:32:0)
[16:03:58.824] Timer tick, asking Buildbucket for the build status
[16:03:58.863] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:58.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:33:0) after 1m0s
[16:04:58.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:33:0)
[16:04:58.881] Timer tick, asking Buildbucket for the build status
[16:04:58.973] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:58.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:34:0) after 1m0s
[16:05:58.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:34:0)
[16:05:58.979] Timer tick, asking Buildbucket for the build status
[16:05:59.026] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:59.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:35:0) after 1m0s
[16:06:59.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:35:0)
[16:06:59.016] Timer tick, asking Buildbucket for the build status
[16:06:59.059] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:59.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:36:0) after 1m0s
[16:07:59.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:36:0)
[16:07:59.139] Timer tick, asking Buildbucket for the build status
[16:07:59.211] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:59.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:37:0) after 1m0s
[16:08:59.267] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:37:0)
[16:08:59.267] Timer tick, asking Buildbucket for the build status
[16:08:59.493] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:59.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:38:0) after 1m0s
[16:09:59.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:38:0)
[16:09:59.469] Timer tick, asking Buildbucket for the build status
[16:09:59.761] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:59.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:39:0) after 1m0s
[16:10:59.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:39:0)
[16:10:59.776] Timer tick, asking Buildbucket for the build status
[16:10:59.871] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:59.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:40:0) after 1m0s
[16:11:59.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:40:0)
[16:11:59.892] Timer tick, asking Buildbucket for the build status
[16:11:59.946] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:59.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:41:0) after 1m0s
[16:12:59.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:41:0)
[16:12:59.970] Timer tick, asking Buildbucket for the build status
[16:13:00.018] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:00.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:42:0) after 1m0s
[16:14:00.043] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:42:0)
[16:14:00.043] Timer tick, asking Buildbucket for the build status
[16:14:00.102] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:00.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:43:0) after 1m0s
[16:15:00.126] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:43:0)
[16:15:00.126] Timer tick, asking Buildbucket for the build status
[16:15:00.175] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:00.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:44:0) after 1m0s
[16:16:00.178] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:44:0)
[16:16:00.178] Timer tick, asking Buildbucket for the build status
[16:16:00.230] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:00.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:45:0) after 1m0s
[16:17:00.263] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:45:0)
[16:17:00.263] Timer tick, asking Buildbucket for the build status
[16:17:00.326] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:00.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:46:0) after 1m0s
[16:18:00.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:46:0)
[16:18:00.363] Timer tick, asking Buildbucket for the build status
[16:18:00.431] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:00.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:47:0) after 1m0s
[16:19:00.450] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:47:0)
[16:19:00.450] Timer tick, asking Buildbucket for the build status
[16:19:00.500] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:00.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:48:0) after 1m0s
[16:20:00.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:48:0)
[16:20:00.479] Timer tick, asking Buildbucket for the build status
[16:20:00.678] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:00.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:49:0) after 1m0s
[16:21:00.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:49:0)
[16:21:00.717] Timer tick, asking Buildbucket for the build status
[16:21:00.785] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:00.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:50:0) after 1m0s
[16:22:00.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:50:0)
[16:22:00.808] Timer tick, asking Buildbucket for the build status
[16:22:00.861] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:00.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:51:0) after 1m0s
[16:23:00.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:51:0)
[16:23:00.867] Timer tick, asking Buildbucket for the build status
[16:23:00.920] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:00.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:52:0) after 1m0s
[16:24:00.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:52:0)
[16:24:00.916] Timer tick, asking Buildbucket for the build status
[16:24:00.974] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:00.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:53:0) after 1m0s
[16:25:00.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:53:0)
[16:25:00.987] Timer tick, asking Buildbucket for the build status
[16:25:01.052] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:01.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:54:0) after 1m0s
[16:26:01.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:54:0)
[16:26:01.077] Timer tick, asking Buildbucket for the build status
[16:26:01.119] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:01.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:55:0) after 1m0s
[16:27:01.143] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:55:0)
[16:27:01.143] Timer tick, asking Buildbucket for the build status
[16:27:01.276] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:01.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:56:0) after 1m0s
[16:28:01.267] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:56:0)
[16:28:01.267] Timer tick, asking Buildbucket for the build status
[16:28:01.400] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:01.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:57:0) after 1m0s
[16:29:01.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:57:0)
[16:29:01.397] Timer tick, asking Buildbucket for the build status
[16:29:01.884] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:01.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:58:0) after 1m0s
[16:30:01.901] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:58:0)
[16:30:01.901] Timer tick, asking Buildbucket for the build status
[16:30:02.117] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:02.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:59:0) after 1m0s
[16:31:02.150] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:59:0)
[16:31:02.150] Timer tick, asking Buildbucket for the build status
[16:31:02.430] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:02.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:60:0) after 1m0s
[16:32:02.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:60:0)
[16:32:02.417] Timer tick, asking Buildbucket for the build status
[16:32:02.465] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:02.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:61:0) after 1m0s
[16:33:02.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:61:0)
[16:33:02.491] Timer tick, asking Buildbucket for the build status
[16:33:02.534] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:02.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:62:0) after 1m0s
[16:34:02.555] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:62:0)
[16:34:02.555] Timer tick, asking Buildbucket for the build status
[16:34:02.643] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:02.643] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:63:0) after 1m0s
[16:35:02.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:63:0)
[16:35:02.664] Timer tick, asking Buildbucket for the build status
[16:35:02.768] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:02.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:64:0) after 1m0s
[16:36:02.789] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:64:0)
[16:36:02.789] Timer tick, asking Buildbucket for the build status
[16:36:02.865] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:02.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:65:0) after 1m0s
[16:37:02.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:65:0)
[16:37:02.889] Timer tick, asking Buildbucket for the build status
[16:37:03.069] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:03.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:66:0) after 1m0s
[16:38:03.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:66:0)
[16:38:03.068] Timer tick, asking Buildbucket for the build status
[16:38:03.131] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:03.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:67:0) after 1m0s
[16:39:03.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:67:0)
[16:39:03.144] Timer tick, asking Buildbucket for the build status
[16:39:03.207] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:03.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:68:0) after 1m0s
[16:40:03.242] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:68:0)
[16:40:03.242] Timer tick, asking Buildbucket for the build status
[16:40:03.320] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:03.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:69:0) after 1m0s
[16:41:03.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:69:0)
[16:41:03.342] Timer tick, asking Buildbucket for the build status
[16:41:03.682] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:03.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:70:0) after 1m0s
[16:42:03.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:70:0)
[16:42:03.674] Timer tick, asking Buildbucket for the build status
[16:42:03.788] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:03.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:71:0) after 1m0s
[16:43:03.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:71:0)
[16:43:03.803] Timer tick, asking Buildbucket for the build status
[16:43:03.844] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:03.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:72:0) after 1m0s
[16:44:03.825] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:72:0)
[16:44:03.825] Timer tick, asking Buildbucket for the build status
[16:44:03.863] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:03.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:73:0) after 1m0s
[16:45:03.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:73:0)
[16:45:03.883] Timer tick, asking Buildbucket for the build status
[16:45:03.981] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:03.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:74:0) after 1m0s
[16:46:03.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:74:0)
[16:46:03.996] Timer tick, asking Buildbucket for the build status
[16:46:04.075] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:04.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:75:0) after 1m0s
[16:47:04.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:75:0)
[16:47:04.067] Timer tick, asking Buildbucket for the build status
[16:47:04.165] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:04.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:76:0) after 1m0s
[16:48:04.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:76:0)
[16:48:04.194] Timer tick, asking Buildbucket for the build status
[16:48:04.240] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:04.240] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:77:0) after 1m0s
[16:49:04.272] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:77:0)
[16:49:04.272] Timer tick, asking Buildbucket for the build status
[16:49:04.350] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:04.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:78:0) after 1m0s
[16:50:04.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:78:0)
[16:50:04.388] Timer tick, asking Buildbucket for the build status
[16:50:04.456] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:04.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:79:0) after 1m0s
[16:51:04.462] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:79:0)
[16:51:04.462] Timer tick, asking Buildbucket for the build status
[16:51:04.583] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:04.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:80:0) after 1m0s
[16:52:04.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:80:0)
[16:52:04.619] Timer tick, asking Buildbucket for the build status
[16:52:04.772] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:04.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:81:0) after 1m0s
[16:53:04.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:81:0)
[16:53:04.805] Timer tick, asking Buildbucket for the build status
[16:53:04.884] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:04.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:82:0) after 1m0s
[16:54:04.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:82:0)
[16:54:04.857] Timer tick, asking Buildbucket for the build status
[16:54:04.911] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:04.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:83:0) after 1m0s
[16:55:04.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:83:0)
[16:55:04.933] Timer tick, asking Buildbucket for the build status
[16:55:05.194] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:05.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:84:0) after 1m0s
[16:56:05.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:84:0)
[16:56:05.172] Timer tick, asking Buildbucket for the build status
[16:56:05.223] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:05.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:85:0) after 1m0s
[16:57:05.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:85:0)
[16:57:05.209] Timer tick, asking Buildbucket for the build status
[16:57:05.254] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:05.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:86:0) after 1m0s
[16:58:05.256] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:86:0)
[16:58:05.256] Timer tick, asking Buildbucket for the build status
[16:58:05.379] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:05.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:87:0) after 1m0s
[16:59:05.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:87:0)
[16:59:05.414] Timer tick, asking Buildbucket for the build status
[16:59:05.612] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:05.612] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:88:0) after 1m0s
[17:00:05.617] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:88:0)
[17:00:05.617] Timer tick, asking Buildbucket for the build status
[17:00:05.667] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:05.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:89:0) after 1m0s
[17:01:05.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:89:0)
[17:01:05.668] Timer tick, asking Buildbucket for the build status
[17:01:05.879] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:05.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:90:0) after 1m0s
[17:02:05.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:90:0)
[17:02:05.882] Timer tick, asking Buildbucket for the build status
[17:02:05.947] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:05.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:91:0) after 1m0s
[17:03:05.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:91:0)
[17:03:05.942] Timer tick, asking Buildbucket for the build status
[17:03:06.047] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:06.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:92:0) after 1m0s
[17:04:06.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:92:0)
[17:04:06.075] Timer tick, asking Buildbucket for the build status
[17:04:06.215] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:06.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:93:0) after 1m0s
[17:05:06.240] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:93:0)
[17:05:06.240] Timer tick, asking Buildbucket for the build status
[17:05:06.293] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:06.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:94:0) after 1m0s
[17:06:06.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:94:0)
[17:06:06.301] Timer tick, asking Buildbucket for the build status
[17:06:06.347] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:06.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:95:0) after 1m0s
[17:07:06.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:95:0)
[17:07:06.374] Timer tick, asking Buildbucket for the build status
[17:07:06.509] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:06.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:96:0) after 1m0s
[17:08:06.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:96:0)
[17:08:06.529] Timer tick, asking Buildbucket for the build status
[17:08:06.603] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:06.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:97:0) after 1m0s
[17:09:06.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:97:0)
[17:09:06.651] Timer tick, asking Buildbucket for the build status
[17:09:06.709] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:06.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:98:0) after 1m0s
[17:10:06.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:98:0)
[17:10:06.785] Timer tick, asking Buildbucket for the build status
[17:10:06.866] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:06.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:99:0) after 1m0s
[17:11:06.939] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:99:0)
[17:11:06.939] Timer tick, asking Buildbucket for the build status
[17:11:07.022] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:07.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:100:0) after 1m0s
[17:12:07.024] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:100:0)
[17:12:07.024] Timer tick, asking Buildbucket for the build status
[17:12:07.634] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:07.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:101:0) after 1m0s
[17:13:07.613] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:101:0)
[17:13:07.613] Timer tick, asking Buildbucket for the build status
[17:13:07.721] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:07.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:102:0) after 1m0s
[17:14:07.747] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:102:0)
[17:14:07.747] Timer tick, asking Buildbucket for the build status
[17:14:07.799] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:07.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:103:0) after 1m0s
[17:15:07.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:103:0)
[17:15:07.815] Timer tick, asking Buildbucket for the build status
[17:15:07.986] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:07.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:104:0) after 1m0s
[17:16:08.043] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:104:0)
[17:16:08.043] Timer tick, asking Buildbucket for the build status
[17:16:08.090] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:08.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:105:0) after 1m0s
[17:17:08.165] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:105:0)
[17:17:08.165] Timer tick, asking Buildbucket for the build status
[17:17:08.271] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:08.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:106:0) after 1m0s
[17:18:08.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:106:0)
[17:18:08.323] Timer tick, asking Buildbucket for the build status
[17:18:08.512] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:08.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:107:0) after 1m0s
[17:19:08.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:107:0)
[17:19:08.568] Timer tick, asking Buildbucket for the build status
[17:19:08.810] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:08.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:108:0) after 1m0s
[17:20:08.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:108:0)
[17:20:08.821] Timer tick, asking Buildbucket for the build status
[17:20:08.861] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:08.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:109:0) after 1m0s
[17:21:08.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:109:0)
[17:21:08.884] Timer tick, asking Buildbucket for the build status
[17:21:08.952] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:08.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:110:0) after 1m0s
[17:22:08.960] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:110:0)
[17:22:08.960] Timer tick, asking Buildbucket for the build status
[17:22:09.022] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:09.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:111:0) after 1m0s
[17:23:09.062] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:111:0)
[17:23:09.062] Timer tick, asking Buildbucket for the build status
[17:23:09.129] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:09.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:112:0) after 1m0s
[17:24:09.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:112:0)
[17:24:09.248] Timer tick, asking Buildbucket for the build status
[17:24:09.309] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:09.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:113:0) after 1m0s
[17:25:09.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:113:0)
[17:25:09.361] Timer tick, asking Buildbucket for the build status
[17:25:09.508] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:09.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:114:0) after 1m0s
[17:26:09.505] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:114:0)
[17:26:09.505] Timer tick, asking Buildbucket for the build status
[17:26:09.552] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:09.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:115:0) after 1m0s
[17:27:09.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:115:0)
[17:27:09.583] Timer tick, asking Buildbucket for the build status
[17:27:09.647] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:09.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:116:0) after 1m0s
[17:28:09.680] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:116:0)
[17:28:09.680] Timer tick, asking Buildbucket for the build status
[17:28:09.750] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:09.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:117:0) after 1m0s
[17:29:09.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:117:0)
[17:29:09.785] Timer tick, asking Buildbucket for the build status
[17:29:09.837] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:09.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:118:0) after 1m0s
[17:30:09.890] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:118:0)
[17:30:09.890] Timer tick, asking Buildbucket for the build status
[17:30:09.934] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:09.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:119:0) after 1m0s
[17:31:09.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:119:0)
[17:31:09.967] Timer tick, asking Buildbucket for the build status
[17:31:10.049] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:10.049] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:120:0) after 1m0s
[17:32:10.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:120:0)
[17:32:10.144] Timer tick, asking Buildbucket for the build status
[17:32:10.197] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:10.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:121:0) after 1m0s
[17:33:10.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:121:0)
[17:33:10.224] Timer tick, asking Buildbucket for the build status
[17:33:10.365] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:10.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:122:0) after 1m0s
[17:34:10.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:122:0)
[17:34:10.397] Timer tick, asking Buildbucket for the build status
[17:34:10.471] Build 8919632250127953504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:10.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9085062312257522944:123:0) after 1m0s
[17:34:38.242] Received PubSub notification, asking Buildbucket for the build status
[17:34:38.283] Build 8919632250127953504: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:34:38.283] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1551980077549802",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551972833472993",
"failure_reason": "BUILD_FAILURE",
"id": "8919632250127953504",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a5ecceed1fbf512fbde09b30dc59f793547c4981\"}}",
"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\": [\"4154-750e250\"], \"machine_type\": [\"n1-standard-1\"], \"gpu\": [\"none\"], \"cores\": [\"1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"id\": [\"fuchsia-debian-1-03c1d9de-us-central1-b-c2hh\"], \"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\", \"revision\": \"a5ecceed1fbf512fbde09b30dc59f793547c4981\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"bot_id\": \"fuchsia-debian-1-03c1d9de-us-central1-b-c2hh\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1551972848563721",
"status": "COMPLETED",
"status_changed_ts": "1551980077943449",
"tags": [
"builder:clang",
"buildset:commit/git/a5ecceed1fbf512fbde09b30dc59f793547c4981",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a5ecceed1fbf512fbde09b30dc59f793547c4981",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085062312257522944",
"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/8919632250127953504/+/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:43719ba44ddb8610",
"user_agent:luci-scheduler"
],
"updated_ts": "1551980077944692",
"url": "https://ci.chromium.org/b/8919632250127953504",
"utcnow_ts": "1551980078275637"
}
}
[17:34:38.283] Invocation finished in 2h0m46.22668223s with status FAILED