[00:01:31.254] New invocation is queued and will start shortly
[00:01:33.223] Starting the invocation (attempt 1)
[00:01:33.263] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:01:33.263] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:01:33.263] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081950076660272976",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"ff79ee144cd62ee4b6eae2f2f809130cda32cadb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081950076660272976",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/ff79ee144cd62ee4b6eae2f2f809130cda32cadb",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ff79ee144cd62ee4b6eae2f2f809130cda32cadb",
"gitiles_ref:refs/heads/master"
]
}
[00:01:34.009] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1554940893387068",
"id": "8916520013733762112",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"ff79ee144cd62ee4b6eae2f2f809130cda32cadb\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554940893712897",
"tags": [
"builder:clang",
"buildset:commit/git/ff79ee144cd62ee4b6eae2f2f809130cda32cadb",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ff79ee144cd62ee4b6eae2f2f809130cda32cadb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081950076660272976",
"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/8916520013733762112/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:buildbucket_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1554940893713000",
"url": "https://ci.chromium.org/b/8916520013733762112",
"utcnow_ts": "1554940893900826"
}
}
[00:01:34.009] Task URL: https://ci.chromium.org/b/8916520013733762112
[00:01:34.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:2:0) after 1m0s
[00:01:54.637] Received PubSub notification, asking Buildbucket for the build status
[00:01:54.768] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:34.030] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:2:0)
[00:02:34.030] Timer tick, asking Buildbucket for the build status
[00:02:34.161] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:34.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:4:0) after 1m0s
[00:03:34.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:4:0)
[00:03:34.198] Timer tick, asking Buildbucket for the build status
[00:03:34.376] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:34.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:5:0) after 1m0s
[00:04:34.367] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:5:0)
[00:04:34.367] Timer tick, asking Buildbucket for the build status
[00:04:34.486] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:34.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:6:0) after 1m0s
[00:05:34.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:6:0)
[00:05:34.482] Timer tick, asking Buildbucket for the build status
[00:05:34.565] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:34.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:7:0) after 1m0s
[00:06:34.547] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:7:0)
[00:06:34.547] Timer tick, asking Buildbucket for the build status
[00:06:34.676] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:34.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:8:0) after 1m0s
[00:07:34.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:8:0)
[00:07:34.712] Timer tick, asking Buildbucket for the build status
[00:07:34.749] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:34.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:9:0) after 1m0s
[00:08:34.759] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:9:0)
[00:08:34.759] Timer tick, asking Buildbucket for the build status
[00:08:34.993] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:34.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:10:0) after 1m0s
[00:09:35.021] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:10:0)
[00:09:35.021] Timer tick, asking Buildbucket for the build status
[00:09:35.083] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:35.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:11:0) after 1m0s
[00:10:35.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:11:0)
[00:10:35.129] Timer tick, asking Buildbucket for the build status
[00:10:35.774] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:35.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:12:0) after 1m0s
[00:11:35.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:12:0)
[00:11:35.794] Timer tick, asking Buildbucket for the build status
[00:11:35.854] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:35.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:13:0) after 1m0s
[00:12:35.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:13:0)
[00:12:35.873] Timer tick, asking Buildbucket for the build status
[00:12:35.912] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:35.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:14:0) after 1m0s
[00:13:35.931] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:14:0)
[00:13:35.931] Timer tick, asking Buildbucket for the build status
[00:13:36.087] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:36.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:15:0) after 1m0s
[00:14:36.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:15:0)
[00:14:36.116] Timer tick, asking Buildbucket for the build status
[00:14:36.208] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:36.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:16:0) after 1m0s
[00:15:36.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:16:0)
[00:15:36.209] Timer tick, asking Buildbucket for the build status
[00:15:36.296] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:36.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:17:0) after 1m0s
[00:16:36.284] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:17:0)
[00:16:36.284] Timer tick, asking Buildbucket for the build status
[00:16:36.491] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:36.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:18:0) after 1m0s
[00:17:36.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:18:0)
[00:17:36.483] Timer tick, asking Buildbucket for the build status
[00:17:36.697] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:36.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:19:0) after 1m0s
[00:18:36.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:19:0)
[00:18:36.716] Timer tick, asking Buildbucket for the build status
[00:18:36.753] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:36.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:20:0) after 1m0s
[00:19:36.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:20:0)
[00:19:36.770] Timer tick, asking Buildbucket for the build status
[00:19:36.882] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:36.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:21:0) after 1m0s
[00:20:36.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:21:0)
[00:20:36.906] Timer tick, asking Buildbucket for the build status
[00:20:37.056] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:37.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:22:0) after 1m0s
[00:21:37.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:22:0)
[00:21:37.072] Timer tick, asking Buildbucket for the build status
[00:21:37.126] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:37.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:23:0) after 1m0s
[00:22:37.132] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:23:0)
[00:22:37.132] Timer tick, asking Buildbucket for the build status
[00:22:37.271] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:37.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:24:0) after 1m0s
[00:23:37.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:24:0)
[00:23:37.305] Timer tick, asking Buildbucket for the build status
[00:23:37.435] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:37.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:25:0) after 1m0s
[00:24:37.441] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:25:0)
[00:24:37.441] Timer tick, asking Buildbucket for the build status
[00:24:37.541] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:37.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:26:0) after 1m0s
[00:25:37.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:26:0)
[00:25:37.561] Timer tick, asking Buildbucket for the build status
[00:25:37.653] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:37.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:27:0) after 1m0s
[00:26:37.691] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:27:0)
[00:26:37.691] Timer tick, asking Buildbucket for the build status
[00:26:37.996] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:37.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:28:0) after 1m0s
[00:27:38.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:28:0)
[00:27:38.027] Timer tick, asking Buildbucket for the build status
[00:27:38.074] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:38.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:29:0) after 1m0s
[00:28:38.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:29:0)
[00:28:38.096] Timer tick, asking Buildbucket for the build status
[00:28:38.164] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:38.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:30:0) after 1m0s
[00:29:38.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:30:0)
[00:29:38.183] Timer tick, asking Buildbucket for the build status
[00:29:38.223] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:38.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:31:0) after 1m0s
[00:30:38.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:31:0)
[00:30:38.274] Timer tick, asking Buildbucket for the build status
[00:30:38.596] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:38.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:32:0) after 1m0s
[00:31:38.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:32:0)
[00:31:38.608] Timer tick, asking Buildbucket for the build status
[00:31:38.647] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:38.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:33:0) after 1m0s
[00:32:38.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:33:0)
[00:32:38.620] Timer tick, asking Buildbucket for the build status
[00:32:38.753] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:38.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:34:0) after 1m0s
[00:33:38.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:34:0)
[00:33:38.755] Timer tick, asking Buildbucket for the build status
[00:33:38.865] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:38.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:35:0) after 1m0s
[00:34:38.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:35:0)
[00:34:38.880] Timer tick, asking Buildbucket for the build status
[00:34:38.989] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:38.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:36:0) after 1m0s
[00:35:39.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:36:0)
[00:35:39.072] Timer tick, asking Buildbucket for the build status
[00:35:39.116] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:39.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:37:0) after 1m0s
[00:36:39.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:37:0)
[00:36:39.138] Timer tick, asking Buildbucket for the build status
[00:36:39.198] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:39.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:38:0) after 1m0s
[00:37:39.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:38:0)
[00:37:39.224] Timer tick, asking Buildbucket for the build status
[00:37:39.308] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:39.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:39:0) after 1m0s
[00:38:39.329] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:39:0)
[00:38:39.329] Timer tick, asking Buildbucket for the build status
[00:38:39.379] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:39.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:40:0) after 1m0s
[00:39:39.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:40:0)
[00:39:39.398] Timer tick, asking Buildbucket for the build status
[00:39:39.474] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:39.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:41:0) after 1m0s
[00:40:39.498] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:41:0)
[00:40:39.498] Timer tick, asking Buildbucket for the build status
[00:40:39.663] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:39.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:42:0) after 1m0s
[00:41:39.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:42:0)
[00:41:39.677] Timer tick, asking Buildbucket for the build status
[00:41:39.719] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:39.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:43:0) after 1m0s
[00:42:39.824] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:43:0)
[00:42:39.824] Timer tick, asking Buildbucket for the build status
[00:42:39.891] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:39.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:44:0) after 1m0s
[00:43:39.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:44:0)
[00:43:39.906] Timer tick, asking Buildbucket for the build status
[00:43:39.948] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:39.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:45:0) after 1m0s
[00:44:39.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:45:0)
[00:44:39.993] Timer tick, asking Buildbucket for the build status
[00:44:40.050] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:40.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:46:0) after 1m0s
[00:45:40.054] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:46:0)
[00:45:40.055] Timer tick, asking Buildbucket for the build status
[00:45:40.099] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:40.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:47:0) after 1m0s
[00:46:40.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:47:0)
[00:46:40.116] Timer tick, asking Buildbucket for the build status
[00:46:40.196] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:40.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:48:0) after 1m0s
[00:47:40.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:48:0)
[00:47:40.243] Timer tick, asking Buildbucket for the build status
[00:47:40.352] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:40.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:49:0) after 1m0s
[00:48:40.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:49:0)
[00:48:40.346] Timer tick, asking Buildbucket for the build status
[00:48:40.387] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:40.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:50:0) after 1m0s
[00:49:40.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:50:0)
[00:49:40.407] Timer tick, asking Buildbucket for the build status
[00:49:40.478] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:40.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:51:0) after 1m0s
[00:50:40.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:51:0)
[00:50:40.499] Timer tick, asking Buildbucket for the build status
[00:50:40.689] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:40.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:52:0) after 1m0s
[00:51:40.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:52:0)
[00:51:40.710] Timer tick, asking Buildbucket for the build status
[00:51:40.747] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:40.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:53:0) after 1m0s
[00:52:40.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:53:0)
[00:52:40.766] Timer tick, asking Buildbucket for the build status
[00:52:40.910] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:40.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:54:0) after 1m0s
[00:53:40.931] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:54:0)
[00:53:40.931] Timer tick, asking Buildbucket for the build status
[00:53:41.072] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:41.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:55:0) after 1m0s
[00:54:41.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:55:0)
[00:54:41.093] Timer tick, asking Buildbucket for the build status
[00:54:41.132] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:41.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:56:0) after 1m0s
[00:55:41.158] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:56:0)
[00:55:41.158] Timer tick, asking Buildbucket for the build status
[00:55:41.227] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:41.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:57:0) after 1m0s
[00:56:41.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:57:0)
[00:56:41.246] Timer tick, asking Buildbucket for the build status
[00:56:41.300] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:41.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:58:0) after 1m0s
[00:57:41.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:58:0)
[00:57:41.308] Timer tick, asking Buildbucket for the build status
[00:57:41.350] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:41.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:59:0) after 1m0s
[00:58:41.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:59:0)
[00:58:41.371] Timer tick, asking Buildbucket for the build status
[00:58:41.423] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:41.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:60:0) after 1m0s
[00:59:41.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:60:0)
[00:59:41.393] Timer tick, asking Buildbucket for the build status
[00:59:41.433] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:41.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:61:0) after 1m0s
[01:00:41.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:61:0)
[01:00:41.468] Timer tick, asking Buildbucket for the build status
[01:00:41.583] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:41.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:62:0) after 1m0s
[01:01:41.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:62:0)
[01:01:41.604] Timer tick, asking Buildbucket for the build status
[01:01:41.658] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:41.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:63:0) after 1m0s
[01:02:41.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:63:0)
[01:02:41.641] Timer tick, asking Buildbucket for the build status
[01:02:41.682] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:41.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:64:0) after 1m0s
[01:03:41.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:64:0)
[01:03:41.684] Timer tick, asking Buildbucket for the build status
[01:03:41.743] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:41.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:65:0) after 1m0s
[01:04:41.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:65:0)
[01:04:41.765] Timer tick, asking Buildbucket for the build status
[01:04:41.998] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:41.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:66:0) after 1m0s
[01:05:42.007] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:66:0)
[01:05:42.007] Timer tick, asking Buildbucket for the build status
[01:05:42.046] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:42.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:67:0) after 1m0s
[01:06:42.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:67:0)
[01:06:42.085] Timer tick, asking Buildbucket for the build status
[01:06:42.183] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:42.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:68:0) after 1m0s
[01:07:42.162] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:68:0)
[01:07:42.162] Timer tick, asking Buildbucket for the build status
[01:07:42.211] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:42.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:69:0) after 1m0s
[01:08:42.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:69:0)
[01:08:42.248] Timer tick, asking Buildbucket for the build status
[01:08:42.590] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:42.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:70:0) after 1m0s
[01:09:42.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:70:0)
[01:09:42.569] Timer tick, asking Buildbucket for the build status
[01:09:42.694] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:42.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:71:0) after 1m0s
[01:10:42.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:71:0)
[01:10:42.694] Timer tick, asking Buildbucket for the build status
[01:10:42.753] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:42.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:72:0) after 1m0s
[01:11:42.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:72:0)
[01:11:42.796] Timer tick, asking Buildbucket for the build status
[01:11:42.989] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:42.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:73:0) after 1m0s
[01:12:43.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:73:0)
[01:12:43.009] Timer tick, asking Buildbucket for the build status
[01:12:43.073] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:43.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:74:0) after 1m0s
[01:13:43.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:74:0)
[01:13:43.069] Timer tick, asking Buildbucket for the build status
[01:13:43.131] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:43.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:75:0) after 1m0s
[01:14:43.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:75:0)
[01:14:43.152] Timer tick, asking Buildbucket for the build status
[01:14:43.480] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:43.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:76:0) after 1m0s
[01:15:43.505] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:76:0)
[01:15:43.505] Timer tick, asking Buildbucket for the build status
[01:15:43.544] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:43.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:77:0) after 1m0s
[01:16:43.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:77:0)
[01:16:43.582] Timer tick, asking Buildbucket for the build status
[01:16:43.653] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:43.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:78:0) after 1m0s
[01:17:43.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:78:0)
[01:17:43.673] Timer tick, asking Buildbucket for the build status
[01:17:43.710] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:43.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:79:0) after 1m0s
[01:18:43.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:79:0)
[01:18:43.755] Timer tick, asking Buildbucket for the build status
[01:18:43.815] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:43.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:80:0) after 1m0s
[01:19:43.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:80:0)
[01:19:43.819] Timer tick, asking Buildbucket for the build status
[01:19:44.089] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:44.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:81:0) after 1m0s
[01:20:44.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:81:0)
[01:20:44.099] Timer tick, asking Buildbucket for the build status
[01:20:44.162] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:44.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:82:0) after 1m0s
[01:21:44.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:82:0)
[01:21:44.182] Timer tick, asking Buildbucket for the build status
[01:21:44.375] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:44.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:83:0) after 1m0s
[01:22:44.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:83:0)
[01:22:44.397] Timer tick, asking Buildbucket for the build status
[01:22:44.458] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:44.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:84:0) after 1m0s
[01:23:44.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:84:0)
[01:23:44.476] Timer tick, asking Buildbucket for the build status
[01:23:44.529] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:44.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:85:0) after 1m0s
[01:24:44.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:85:0)
[01:24:44.551] Timer tick, asking Buildbucket for the build status
[01:24:44.607] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:44.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:86:0) after 1m0s
[01:25:44.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:86:0)
[01:25:44.580] Timer tick, asking Buildbucket for the build status
[01:25:44.688] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:44.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:87:0) after 1m0s
[01:26:44.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:87:0)
[01:26:44.694] Timer tick, asking Buildbucket for the build status
[01:26:44.768] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:44.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:88:0) after 1m0s
[01:27:44.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:88:0)
[01:27:44.791] Timer tick, asking Buildbucket for the build status
[01:27:44.952] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:44.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:89:0) after 1m0s
[01:28:44.973] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:89:0)
[01:28:44.973] Timer tick, asking Buildbucket for the build status
[01:28:45.031] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:45.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:90:0) after 1m0s
[01:29:45.052] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:90:0)
[01:29:45.052] Timer tick, asking Buildbucket for the build status
[01:29:45.122] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:45.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:91:0) after 1m0s
[01:30:45.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:91:0)
[01:30:45.111] Timer tick, asking Buildbucket for the build status
[01:30:45.250] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:45.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:92:0) after 1m0s
[01:31:45.261] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:92:0)
[01:31:45.261] Timer tick, asking Buildbucket for the build status
[01:31:45.365] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:45.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:93:0) after 1m0s
[01:32:45.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:93:0)
[01:32:45.384] Timer tick, asking Buildbucket for the build status
[01:32:45.443] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:45.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:94:0) after 1m0s
[01:33:45.467] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:94:0)
[01:33:45.467] Timer tick, asking Buildbucket for the build status
[01:33:45.522] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:45.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:95:0) after 1m0s
[01:34:45.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:95:0)
[01:34:45.503] Timer tick, asking Buildbucket for the build status
[01:34:45.594] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:45.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:96:0) after 1m0s
[01:35:45.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:96:0)
[01:35:45.595] Timer tick, asking Buildbucket for the build status
[01:35:45.655] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:45.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:97:0) after 1m0s
[01:36:45.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:97:0)
[01:36:45.678] Timer tick, asking Buildbucket for the build status
[01:36:45.744] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:45.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:98:0) after 1m0s
[01:37:45.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:98:0)
[01:37:45.771] Timer tick, asking Buildbucket for the build status
[01:37:46.134] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:46.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:99:0) after 1m0s
[01:38:46.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:99:0)
[01:38:46.169] Timer tick, asking Buildbucket for the build status
[01:38:46.299] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:46.299] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:100:0) after 1m0s
[01:39:46.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:100:0)
[01:39:46.323] Timer tick, asking Buildbucket for the build status
[01:39:46.482] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:46.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:101:0) after 1m0s
[01:40:46.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:101:0)
[01:40:46.517] Timer tick, asking Buildbucket for the build status
[01:40:46.592] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:46.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:102:0) after 1m0s
[01:41:46.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:102:0)
[01:41:46.601] Timer tick, asking Buildbucket for the build status
[01:41:46.742] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:46.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:103:0) after 1m0s
[01:42:46.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:103:0)
[01:42:46.771] Timer tick, asking Buildbucket for the build status
[01:42:47.041] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:47.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:104:0) after 1m0s
[01:43:47.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:104:0)
[01:43:47.027] Timer tick, asking Buildbucket for the build status
[01:43:47.103] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:47.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:105:0) after 1m0s
[01:44:47.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:105:0)
[01:44:47.103] Timer tick, asking Buildbucket for the build status
[01:44:47.174] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:47.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:106:0) after 1m0s
[01:45:47.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:106:0)
[01:45:47.196] Timer tick, asking Buildbucket for the build status
[01:45:47.324] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:47.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:107:0) after 1m0s
[01:46:47.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:107:0)
[01:46:47.346] Timer tick, asking Buildbucket for the build status
[01:46:47.427] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:47.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:108:0) after 1m0s
[01:47:47.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:108:0)
[01:47:47.449] Timer tick, asking Buildbucket for the build status
[01:47:47.535] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:47.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:109:0) after 1m0s
[01:48:47.545] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:109:0)
[01:48:47.545] Timer tick, asking Buildbucket for the build status
[01:48:47.632] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:47.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:110:0) after 1m0s
[01:49:47.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:110:0)
[01:49:47.710] Timer tick, asking Buildbucket for the build status
[01:49:47.837] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:47.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:111:0) after 1m0s
[01:50:47.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:111:0)
[01:50:47.850] Timer tick, asking Buildbucket for the build status
[01:50:47.933] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:47.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:112:0) after 1m0s
[01:51:47.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:112:0)
[01:51:47.942] Timer tick, asking Buildbucket for the build status
[01:51:48.066] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:48.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:113:0) after 1m0s
[01:52:48.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:113:0)
[01:52:48.089] Timer tick, asking Buildbucket for the build status
[01:52:48.149] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:48.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:114:0) after 1m0s
[01:53:48.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:114:0)
[01:53:48.209] Timer tick, asking Buildbucket for the build status
[01:53:48.264] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:48.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:115:0) after 1m0s
[01:54:48.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:115:0)
[01:54:48.277] Timer tick, asking Buildbucket for the build status
[01:54:48.339] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:48.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:116:0) after 1m0s
[01:55:48.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:116:0)
[01:55:48.362] Timer tick, asking Buildbucket for the build status
[01:55:48.418] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:48.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:117:0) after 1m0s
[01:56:48.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:117:0)
[01:56:48.446] Timer tick, asking Buildbucket for the build status
[01:56:48.613] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:48.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:118:0) after 1m0s
[01:57:48.637] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:118:0)
[01:57:48.637] Timer tick, asking Buildbucket for the build status
[01:57:48.694] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:48.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:119:0) after 1m0s
[01:58:48.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:119:0)
[01:58:48.693] Timer tick, asking Buildbucket for the build status
[01:58:48.730] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:48.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:120:0) after 1m0s
[01:59:48.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:120:0)
[01:59:48.753] Timer tick, asking Buildbucket for the build status
[01:59:48.814] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:48.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:121:0) after 1m0s
[02:00:48.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:121:0)
[02:00:48.836] Timer tick, asking Buildbucket for the build status
[02:00:49.007] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:49.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:122:0) after 1m0s
[02:01:49.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:122:0)
[02:01:49.029] Timer tick, asking Buildbucket for the build status
[02:01:49.173] Build 8916520013733762112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:49.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081950076660272976:123:0) after 1m0s
[02:02:26.793] Received PubSub notification, asking Buildbucket for the build status
[02:02:26.836] Build 8916520013733762112: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:02:26.836] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1554948146480901",
"created_by": "project:fuchsia",
"created_ts": "1554940893387068",
"failure_reason": "BUILD_FAILURE",
"id": "8916520013733762112",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"ff79ee144cd62ee4b6eae2f2f809130cda32cadb\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-c-chxr\", \"branch\": \"refs/heads/master\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"path_config\": \"generic\", \"recipe\": \"buildbucket_trigger\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"ff79ee144cd62ee4b6eae2f2f809130cda32cadb\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-c-chxr\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4243-f38882f\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1554940913952855",
"status": "COMPLETED",
"status_changed_ts": "1554940914369265",
"tags": [
"builder:clang",
"buildset:commit/git/ff79ee144cd62ee4b6eae2f2f809130cda32cadb",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ff79ee144cd62ee4b6eae2f2f809130cda32cadb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081950076660272976",
"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/8916520013733762112/+/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:442284a345c2ce10",
"user_agent:luci-scheduler"
],
"updated_ts": "1554948146553717",
"url": "https://ci.chromium.org/b/8916520013733762112",
"utcnow_ts": "1554948146826728"
}
}
[02:02:26.836] Invocation finished in 2h0m55.622717263s with status FAILED