[01:01:24.351] New invocation is queued and will start shortly
[01:01:25.376] Starting the invocation (attempt 1)
[01:01:25.415] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:01:25.415] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:01:25.416] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080587354502388992",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"1b76063a5e023b39b4a395d7fc68fd5fe1e6aba3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080587354502388992",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/1b76063a5e023b39b4a395d7fc68fd5fe1e6aba3",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1b76063a5e023b39b4a395d7fc68fd5fe1e6aba3",
"gitiles_ref:refs/heads/master"
]
}
[01:01:26.661] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556240485601361",
"id": "8915157292528325728",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"1b76063a5e023b39b4a395d7fc68fd5fe1e6aba3\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556240486402796",
"tags": [
"builder:clang",
"buildset:commit/git/1b76063a5e023b39b4a395d7fc68fd5fe1e6aba3",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1b76063a5e023b39b4a395d7fc68fd5fe1e6aba3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080587354502388992",
"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/8915157292528325728/+/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": "1556240486402913",
"url": "https://ci.chromium.org/b/8915157292528325728",
"utcnow_ts": "1556240486650870"
}
}
[01:01:26.661] Task URL: https://ci.chromium.org/b/8915157292528325728
[01:01:26.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:2:0) after 1m0s
[01:01:42.171] Received PubSub notification, asking Buildbucket for the build status
[01:01:42.236] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:26.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:2:0)
[01:02:26.642] Timer tick, asking Buildbucket for the build status
[01:02:26.783] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:26.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:4:0) after 1m0s
[01:03:26.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:4:0)
[01:03:26.801] Timer tick, asking Buildbucket for the build status
[01:03:26.978] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:26.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:5:0) after 1m0s
[01:04:27.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:5:0)
[01:04:27.006] Timer tick, asking Buildbucket for the build status
[01:04:27.451] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:27.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:6:0) after 1m0s
[01:05:27.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:6:0)
[01:05:27.471] Timer tick, asking Buildbucket for the build status
[01:05:27.577] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:27.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:7:0) after 1m0s
[01:06:27.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:7:0)
[01:06:27.572] Timer tick, asking Buildbucket for the build status
[01:06:27.630] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:27.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:8:0) after 1m0s
[01:07:27.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:8:0)
[01:07:27.646] Timer tick, asking Buildbucket for the build status
[01:07:27.692] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:27.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:9:0) after 1m0s
[01:08:27.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:9:0)
[01:08:27.712] Timer tick, asking Buildbucket for the build status
[01:08:27.752] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:27.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:10:0) after 1m0s
[01:09:27.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:10:0)
[01:09:27.769] Timer tick, asking Buildbucket for the build status
[01:09:27.864] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:27.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:11:0) after 1m0s
[01:10:27.865] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:11:0)
[01:10:27.865] Timer tick, asking Buildbucket for the build status
[01:10:27.911] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:27.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:12:0) after 1m0s
[01:11:27.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:12:0)
[01:11:27.929] Timer tick, asking Buildbucket for the build status
[01:11:27.985] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:27.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:13:0) after 1m0s
[01:12:27.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:13:0)
[01:12:27.976] Timer tick, asking Buildbucket for the build status
[01:12:28.017] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:28.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:14:0) after 1m0s
[01:13:28.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:14:0)
[01:13:28.008] Timer tick, asking Buildbucket for the build status
[01:13:28.115] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:28.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:15:0) after 1m0s
[01:14:28.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:15:0)
[01:14:28.113] Timer tick, asking Buildbucket for the build status
[01:14:28.186] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:28.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:16:0) after 1m0s
[01:15:28.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:16:0)
[01:15:28.213] Timer tick, asking Buildbucket for the build status
[01:15:28.342] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:28.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:17:0) after 1m0s
[01:16:28.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:17:0)
[01:16:28.363] Timer tick, asking Buildbucket for the build status
[01:16:28.422] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:28.422] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:18:0) after 1m0s
[01:17:28.456] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:18:0)
[01:17:28.456] Timer tick, asking Buildbucket for the build status
[01:17:28.550] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:28.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:19:0) after 1m0s
[01:18:28.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:19:0)
[01:18:28.569] Timer tick, asking Buildbucket for the build status
[01:18:28.611] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:28.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:20:0) after 1m0s
[01:19:28.629] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:20:0)
[01:19:28.629] Timer tick, asking Buildbucket for the build status
[01:19:28.715] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:28.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:21:0) after 1m0s
[01:20:28.723] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:21:0)
[01:20:28.723] Timer tick, asking Buildbucket for the build status
[01:20:28.768] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:28.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:22:0) after 1m0s
[01:21:28.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:22:0)
[01:21:28.803] Timer tick, asking Buildbucket for the build status
[01:21:28.862] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:28.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:23:0) after 1m0s
[01:22:28.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:23:0)
[01:22:28.884] Timer tick, asking Buildbucket for the build status
[01:22:28.927] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:28.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:24:0) after 1m0s
[01:23:28.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:24:0)
[01:23:28.950] Timer tick, asking Buildbucket for the build status
[01:23:29.007] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:29.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:25:0) after 1m0s
[01:24:29.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:25:0)
[01:24:29.006] Timer tick, asking Buildbucket for the build status
[01:24:29.057] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:29.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:26:0) after 1m0s
[01:25:29.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:26:0)
[01:25:29.074] Timer tick, asking Buildbucket for the build status
[01:25:29.131] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:29.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:27:0) after 1m0s
[01:26:29.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:27:0)
[01:26:29.153] Timer tick, asking Buildbucket for the build status
[01:26:29.201] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:29.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:28:0) after 1m0s
[01:27:29.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:28:0)
[01:27:29.228] Timer tick, asking Buildbucket for the build status
[01:27:29.288] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:29.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:29:0) after 1m0s
[01:28:29.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:29:0)
[01:28:29.281] Timer tick, asking Buildbucket for the build status
[01:28:29.337] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:29.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:30:0) after 1m0s
[01:29:29.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:30:0)
[01:29:29.317] Timer tick, asking Buildbucket for the build status
[01:29:29.387] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:29.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:31:0) after 1m0s
[01:30:29.419] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:31:0)
[01:30:29.419] Timer tick, asking Buildbucket for the build status
[01:30:29.533] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:29.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:32:0) after 1m0s
[01:31:29.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:32:0)
[01:31:29.507] Timer tick, asking Buildbucket for the build status
[01:31:29.581] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:29.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:33:0) after 1m0s
[01:32:29.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:33:0)
[01:32:29.607] Timer tick, asking Buildbucket for the build status
[01:32:29.751] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:29.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:34:0) after 1m0s
[01:33:29.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:34:0)
[01:33:29.749] Timer tick, asking Buildbucket for the build status
[01:33:29.814] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:29.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:35:0) after 1m0s
[01:34:29.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:35:0)
[01:34:29.827] Timer tick, asking Buildbucket for the build status
[01:34:29.869] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:29.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:36:0) after 1m0s
[01:35:29.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:36:0)
[01:35:29.889] Timer tick, asking Buildbucket for the build status
[01:35:30.055] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:30.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:37:0) after 1m0s
[01:36:30.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:37:0)
[01:36:30.087] Timer tick, asking Buildbucket for the build status
[01:36:30.143] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:30.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:38:0) after 1m0s
[01:37:30.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:38:0)
[01:37:30.172] Timer tick, asking Buildbucket for the build status
[01:37:30.288] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:30.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:39:0) after 1m0s
[01:38:30.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:39:0)
[01:38:30.306] Timer tick, asking Buildbucket for the build status
[01:38:30.348] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:30.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:40:0) after 1m0s
[01:39:30.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:40:0)
[01:39:30.389] Timer tick, asking Buildbucket for the build status
[01:39:30.452] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:30.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:41:0) after 1m0s
[01:40:30.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:41:0)
[01:40:30.475] Timer tick, asking Buildbucket for the build status
[01:40:30.528] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:30.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:42:0) after 1m0s
[01:41:30.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:42:0)
[01:41:30.551] Timer tick, asking Buildbucket for the build status
[01:41:30.627] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:30.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:43:0) after 1m0s
[01:42:30.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:43:0)
[01:42:30.665] Timer tick, asking Buildbucket for the build status
[01:42:30.702] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:30.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:44:0) after 1m0s
[01:43:30.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:44:0)
[01:43:30.734] Timer tick, asking Buildbucket for the build status
[01:43:30.915] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:30.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:45:0) after 1m0s
[01:44:30.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:45:0)
[01:44:30.942] Timer tick, asking Buildbucket for the build status
[01:44:31.072] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:31.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:46:0) after 1m0s
[01:45:31.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:46:0)
[01:45:31.098] Timer tick, asking Buildbucket for the build status
[01:45:31.287] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:31.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:47:0) after 1m0s
[01:46:31.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:47:0)
[01:46:31.280] Timer tick, asking Buildbucket for the build status
[01:46:31.328] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:31.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:48:0) after 1m0s
[01:47:31.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:48:0)
[01:47:31.327] Timer tick, asking Buildbucket for the build status
[01:47:31.389] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:31.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:49:0) after 1m0s
[01:48:31.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:49:0)
[01:48:31.407] Timer tick, asking Buildbucket for the build status
[01:48:31.450] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:31.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:50:0) after 1m0s
[01:49:31.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:50:0)
[01:49:31.443] Timer tick, asking Buildbucket for the build status
[01:49:31.522] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:31.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:51:0) after 1m0s
[01:50:31.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:51:0)
[01:50:31.529] Timer tick, asking Buildbucket for the build status
[01:50:31.577] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:31.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:52:0) after 1m0s
[01:51:31.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:52:0)
[01:51:31.548] Timer tick, asking Buildbucket for the build status
[01:51:31.586] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:31.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:53:0) after 1m0s
[01:52:31.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:53:0)
[01:52:31.609] Timer tick, asking Buildbucket for the build status
[01:52:31.646] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:31.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:54:0) after 1m0s
[01:53:31.663] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:54:0)
[01:53:31.663] Timer tick, asking Buildbucket for the build status
[01:53:31.722] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:31.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:55:0) after 1m0s
[01:54:31.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:55:0)
[01:54:31.741] Timer tick, asking Buildbucket for the build status
[01:54:31.777] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:31.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:56:0) after 1m0s
[01:55:31.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:56:0)
[01:55:31.815] Timer tick, asking Buildbucket for the build status
[01:55:31.988] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:31.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:57:0) after 1m0s
[01:56:31.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:57:0)
[01:56:31.972] Timer tick, asking Buildbucket for the build status
[01:56:32.164] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:32.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:58:0) after 1m0s
[01:57:32.184] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:58:0)
[01:57:32.184] Timer tick, asking Buildbucket for the build status
[01:57:32.237] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:32.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:59:0) after 1m0s
[01:58:32.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:59:0)
[01:58:32.218] Timer tick, asking Buildbucket for the build status
[01:58:32.280] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:32.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:60:0) after 1m0s
[01:59:32.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:60:0)
[01:59:32.315] Timer tick, asking Buildbucket for the build status
[01:59:32.385] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:32.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:61:0) after 1m0s
[02:00:32.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:61:0)
[02:00:32.405] Timer tick, asking Buildbucket for the build status
[02:00:32.481] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:32.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:62:0) after 1m0s
[02:01:32.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:62:0)
[02:01:32.487] Timer tick, asking Buildbucket for the build status
[02:01:32.527] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:32.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:63:0) after 1m0s
[02:02:32.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:63:0)
[02:02:32.526] Timer tick, asking Buildbucket for the build status
[02:02:32.709] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:32.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:64:0) after 1m0s
[02:03:32.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:64:0)
[02:03:32.707] Timer tick, asking Buildbucket for the build status
[02:03:32.876] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:32.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:65:0) after 1m0s
[02:04:32.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:65:0)
[02:04:32.879] Timer tick, asking Buildbucket for the build status
[02:04:32.941] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:32.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:66:0) after 1m0s
[02:05:32.962] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:66:0)
[02:05:32.962] Timer tick, asking Buildbucket for the build status
[02:05:32.999] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:32.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:67:0) after 1m0s
[02:06:32.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:67:0)
[02:06:32.983] Timer tick, asking Buildbucket for the build status
[02:06:33.085] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:33.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:68:0) after 1m0s
[02:07:33.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:68:0)
[02:07:33.107] Timer tick, asking Buildbucket for the build status
[02:07:33.182] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:33.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:69:0) after 1m0s
[02:08:33.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:69:0)
[02:08:33.203] Timer tick, asking Buildbucket for the build status
[02:08:33.359] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:33.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:70:0) after 1m0s
[02:09:33.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:70:0)
[02:09:33.372] Timer tick, asking Buildbucket for the build status
[02:09:33.431] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:33.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:71:0) after 1m0s
[02:10:33.474] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:71:0)
[02:10:33.474] Timer tick, asking Buildbucket for the build status
[02:10:33.555] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:33.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:72:0) after 1m0s
[02:11:33.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:72:0)
[02:11:33.601] Timer tick, asking Buildbucket for the build status
[02:11:33.688] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:33.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:73:0) after 1m0s
[02:12:33.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:73:0)
[02:12:33.698] Timer tick, asking Buildbucket for the build status
[02:12:33.865] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:33.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:74:0) after 1m0s
[02:13:33.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:74:0)
[02:13:33.887] Timer tick, asking Buildbucket for the build status
[02:13:34.208] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:34.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:75:0) after 1m0s
[02:14:34.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:75:0)
[02:14:34.245] Timer tick, asking Buildbucket for the build status
[02:14:34.280] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:34.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:76:0) after 1m0s
[02:15:34.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:76:0)
[02:15:34.300] Timer tick, asking Buildbucket for the build status
[02:15:34.384] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:34.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:77:0) after 1m0s
[02:16:34.409] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:77:0)
[02:16:34.410] Timer tick, asking Buildbucket for the build status
[02:16:34.464] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:34.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:78:0) after 1m0s
[02:17:34.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:78:0)
[02:17:34.486] Timer tick, asking Buildbucket for the build status
[02:17:34.541] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:34.541] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:79:0) after 1m0s
[02:18:34.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:79:0)
[02:18:34.569] Timer tick, asking Buildbucket for the build status
[02:18:34.612] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:34.612] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:80:0) after 1m0s
[02:19:34.653] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:80:0)
[02:19:34.653] Timer tick, asking Buildbucket for the build status
[02:19:34.977] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:34.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:81:0) after 1m0s
[02:20:34.974] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:81:0)
[02:20:34.974] Timer tick, asking Buildbucket for the build status
[02:20:35.120] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:35.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:82:0) after 1m0s
[02:21:35.147] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:82:0)
[02:21:35.147] Timer tick, asking Buildbucket for the build status
[02:21:35.203] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:35.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:83:0) after 1m0s
[02:22:35.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:83:0)
[02:22:35.180] Timer tick, asking Buildbucket for the build status
[02:22:35.233] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:35.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:84:0) after 1m0s
[02:23:35.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:84:0)
[02:23:35.241] Timer tick, asking Buildbucket for the build status
[02:23:35.286] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:35.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:85:0) after 1m0s
[02:24:35.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:85:0)
[02:24:35.318] Timer tick, asking Buildbucket for the build status
[02:24:35.383] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:35.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:86:0) after 1m0s
[02:25:35.353] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:86:0)
[02:25:35.353] Timer tick, asking Buildbucket for the build status
[02:25:35.396] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:35.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:87:0) after 1m0s
[02:26:35.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:87:0)
[02:26:35.404] Timer tick, asking Buildbucket for the build status
[02:26:35.459] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:35.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:88:0) after 1m0s
[02:27:35.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:88:0)
[02:27:35.499] Timer tick, asking Buildbucket for the build status
[02:27:35.580] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:35.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:89:0) after 1m0s
[02:28:35.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:89:0)
[02:28:35.591] Timer tick, asking Buildbucket for the build status
[02:28:35.704] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:35.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:90:0) after 1m0s
[02:29:35.724] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:90:0)
[02:29:35.724] Timer tick, asking Buildbucket for the build status
[02:29:36.366] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:36.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:91:0) after 1m0s
[02:30:36.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:91:0)
[02:30:36.384] Timer tick, asking Buildbucket for the build status
[02:30:36.435] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:36.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:92:0) after 1m0s
[02:31:36.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:92:0)
[02:31:36.463] Timer tick, asking Buildbucket for the build status
[02:31:36.560] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:36.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:93:0) after 1m0s
[02:32:36.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:93:0)
[02:32:36.578] Timer tick, asking Buildbucket for the build status
[02:32:36.637] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:36.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:94:0) after 1m0s
[02:33:36.680] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:94:0)
[02:33:36.680] Timer tick, asking Buildbucket for the build status
[02:33:36.837] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:36.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:95:0) after 1m0s
[02:34:36.860] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:95:0)
[02:34:36.860] Timer tick, asking Buildbucket for the build status
[02:34:36.962] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:36.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:96:0) after 1m0s
[02:35:36.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:96:0)
[02:35:36.972] Timer tick, asking Buildbucket for the build status
[02:35:37.020] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:37.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:97:0) after 1m0s
[02:36:37.051] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:97:0)
[02:36:37.051] Timer tick, asking Buildbucket for the build status
[02:36:37.191] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:37.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:98:0) after 1m0s
[02:37:37.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:98:0)
[02:37:37.215] Timer tick, asking Buildbucket for the build status
[02:37:37.279] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:37.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:99:0) after 1m0s
[02:38:37.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:99:0)
[02:38:37.273] Timer tick, asking Buildbucket for the build status
[02:38:37.451] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:37.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:100:0) after 1m0s
[02:39:37.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:100:0)
[02:39:37.465] Timer tick, asking Buildbucket for the build status
[02:39:37.530] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:37.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:101:0) after 1m0s
[02:40:37.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:101:0)
[02:40:37.540] Timer tick, asking Buildbucket for the build status
[02:40:37.647] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:37.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:102:0) after 1m0s
[02:41:37.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:102:0)
[02:41:37.648] Timer tick, asking Buildbucket for the build status
[02:41:37.710] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:37.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:103:0) after 1m0s
[02:42:37.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:103:0)
[02:42:37.697] Timer tick, asking Buildbucket for the build status
[02:42:37.961] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:37.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:104:0) after 1m0s
[02:43:37.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:104:0)
[02:43:37.965] Timer tick, asking Buildbucket for the build status
[02:43:38.005] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:38.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:105:0) after 1m0s
[02:44:37.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:105:0)
[02:44:37.983] Timer tick, asking Buildbucket for the build status
[02:44:38.059] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:38.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:106:0) after 1m0s
[02:45:38.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:106:0)
[02:45:38.061] Timer tick, asking Buildbucket for the build status
[02:45:38.178] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:38.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:107:0) after 1m0s
[02:46:38.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:107:0)
[02:46:38.173] Timer tick, asking Buildbucket for the build status
[02:46:38.232] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:38.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:108:0) after 1m0s
[02:47:38.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:108:0)
[02:47:38.223] Timer tick, asking Buildbucket for the build status
[02:47:38.396] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:38.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:109:0) after 1m0s
[02:48:38.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:109:0)
[02:48:38.397] Timer tick, asking Buildbucket for the build status
[02:48:38.520] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:38.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:110:0) after 1m0s
[02:49:38.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:110:0)
[02:49:38.507] Timer tick, asking Buildbucket for the build status
[02:49:38.555] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:38.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:111:0) after 1m0s
[02:50:38.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:111:0)
[02:50:38.577] Timer tick, asking Buildbucket for the build status
[02:50:38.650] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:38.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:112:0) after 1m0s
[02:51:38.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:112:0)
[02:51:38.703] Timer tick, asking Buildbucket for the build status
[02:51:38.780] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:38.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:113:0) after 1m0s
[02:52:38.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:113:0)
[02:52:38.801] Timer tick, asking Buildbucket for the build status
[02:52:38.898] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:38.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:114:0) after 1m0s
[02:53:38.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:114:0)
[02:53:38.902] Timer tick, asking Buildbucket for the build status
[02:53:38.937] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:38.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:115:0) after 1m0s
[02:54:38.958] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:115:0)
[02:54:38.958] Timer tick, asking Buildbucket for the build status
[02:54:39.038] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:39.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:116:0) after 1m0s
[02:55:39.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:116:0)
[02:55:39.019] Timer tick, asking Buildbucket for the build status
[02:55:39.085] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:39.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:117:0) after 1m0s
[02:56:39.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:117:0)
[02:56:39.072] Timer tick, asking Buildbucket for the build status
[02:56:39.128] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:39.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:118:0) after 1m0s
[02:57:39.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:118:0)
[02:57:39.172] Timer tick, asking Buildbucket for the build status
[02:57:39.213] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:39.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:119:0) after 1m0s
[02:58:39.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:119:0)
[02:58:39.216] Timer tick, asking Buildbucket for the build status
[02:58:39.273] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:39.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:120:0) after 1m0s
[02:59:39.312] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:120:0)
[02:59:39.312] Timer tick, asking Buildbucket for the build status
[02:59:39.402] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:39.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:121:0) after 1m0s
[03:00:39.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:121:0)
[03:00:39.421] Timer tick, asking Buildbucket for the build status
[03:00:39.518] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:39.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:122:0) after 1m0s
[03:01:39.541] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:122:0)
[03:01:39.541] Timer tick, asking Buildbucket for the build status
[03:01:39.755] Build 8915157292528325728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:39.755] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080587354502388992:123:0) after 1m0s
[03:01:52.286] Received PubSub notification, asking Buildbucket for the build status
[03:01:52.332] Build 8915157292528325728: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:01:52.332] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556247711700533",
"created_by": "project:fuchsia",
"created_ts": "1556240485601361",
"failure_reason": "BUILD_FAILURE",
"id": "8915157292528325728",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"1b76063a5e023b39b4a395d7fc68fd5fe1e6aba3\"}}",
"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-b-rk3n\", \"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\": \"1b76063a5e023b39b4a395d7fc68fd5fe1e6aba3\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-rk3n\"], \"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\": [\"4274-a0a5f8c\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1556240492144746",
"status": "COMPLETED",
"status_changed_ts": "1556240492579151",
"tags": [
"builder:clang",
"buildset:commit/git/1b76063a5e023b39b4a395d7fc68fd5fe1e6aba3",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1b76063a5e023b39b4a395d7fc68fd5fe1e6aba3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080587354502388992",
"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/8915157292528325728/+/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:446ffad98d8e2310",
"user_agent:luci-scheduler"
],
"updated_ts": "1556247711748431",
"url": "https://ci.chromium.org/b/8915157292528325728",
"utcnow_ts": "1556247712325182"
}
}
[03:01:52.332] Invocation finished in 2h0m27.99782506s with status FAILED