[10:02:12.986] New invocation is queued and will start shortly
[10:02:15.644] Starting the invocation (attempt 1)
[10:02:15.681] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:02:15.682] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:02:15.682] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9077473032788482672",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"fa147c97d6ba1a59fe885a193a40a274158f38da\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9077473032788482672",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/fa147c97d6ba1a59fe885a193a40a274158f38da",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/fa147c97d6ba1a59fe885a193a40a274158f38da",
"gitiles_ref:refs/heads/master"
]
}
[10:02:16.656] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1559210535883289",
"id": "8912042969084351616",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"fa147c97d6ba1a59fe885a193a40a274158f38da\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559210536471818",
"tags": [
"builder:clang",
"buildset:commit/git/fa147c97d6ba1a59fe885a193a40a274158f38da",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/fa147c97d6ba1a59fe885a193a40a274158f38da",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077473032788482672",
"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/8912042969084351616/+/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": "1559210536472044",
"url": "https://ci.chromium.org/b/8912042969084351616",
"utcnow_ts": "1559210536645064"
}
}
[10:02:16.656] Task URL: https://ci.chromium.org/b/8912042969084351616
[10:02:16.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:2:0) after 1m0s
[10:02:42.200] Received PubSub notification, asking Buildbucket for the build status
[10:02:42.385] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:17.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:2:0)
[10:03:17.564] Timer tick, asking Buildbucket for the build status
[10:03:17.729] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:17.729] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:4:0) after 1m0s
[10:04:17.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:4:0)
[10:04:17.887] Timer tick, asking Buildbucket for the build status
[10:04:17.966] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:17.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:5:0) after 1m0s
[10:05:18.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:5:0)
[10:05:18.159] Timer tick, asking Buildbucket for the build status
[10:05:18.364] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:18.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:6:0) after 1m0s
[10:06:18.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:6:0)
[10:06:18.572] Timer tick, asking Buildbucket for the build status
[10:06:18.893] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:18.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:7:0) after 1m0s
[10:07:18.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:7:0)
[10:07:18.920] Timer tick, asking Buildbucket for the build status
[10:07:19.016] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:19.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:8:0) after 1m0s
[10:08:19.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:8:0)
[10:08:19.156] Timer tick, asking Buildbucket for the build status
[10:08:19.712] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:19.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:9:0) after 1m0s
[10:09:19.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:9:0)
[10:09:19.822] Timer tick, asking Buildbucket for the build status
[10:09:19.891] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:19.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:10:0) after 1m0s
[10:10:19.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:10:0)
[10:10:19.911] Timer tick, asking Buildbucket for the build status
[10:10:20.254] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:20.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:11:0) after 1m0s
[10:11:20.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:11:0)
[10:11:20.569] Timer tick, asking Buildbucket for the build status
[10:11:20.690] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:20.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:12:0) after 1m0s
[10:12:21.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:12:0)
[10:12:21.118] Timer tick, asking Buildbucket for the build status
[10:12:21.260] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:21.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:13:0) after 1m0s
[10:13:21.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:13:0)
[10:13:21.866] Timer tick, asking Buildbucket for the build status
[10:13:22.442] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:22.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:14:0) after 1m0s
[10:14:22.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:14:0)
[10:14:22.536] Timer tick, asking Buildbucket for the build status
[10:14:22.728] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:22.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:15:0) after 1m0s
[10:15:22.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:15:0)
[10:15:22.796] Timer tick, asking Buildbucket for the build status
[10:15:23.130] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:23.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:16:0) after 1m0s
[10:16:23.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:16:0)
[10:16:23.163] Timer tick, asking Buildbucket for the build status
[10:16:23.237] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:23.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:17:0) after 1m0s
[10:17:23.256] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:17:0)
[10:17:23.256] Timer tick, asking Buildbucket for the build status
[10:17:23.902] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:23.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:18:0) after 1m0s
[10:18:23.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:18:0)
[10:18:23.879] Timer tick, asking Buildbucket for the build status
[10:18:23.955] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:23.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:19:0) after 1m0s
[10:19:23.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:19:0)
[10:19:23.976] Timer tick, asking Buildbucket for the build status
[10:19:24.102] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:24.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:20:0) after 1m0s
[10:20:24.290] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:20:0)
[10:20:24.290] Timer tick, asking Buildbucket for the build status
[10:20:24.349] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:24.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:21:0) after 1m0s
[10:21:24.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:21:0)
[10:21:24.371] Timer tick, asking Buildbucket for the build status
[10:21:24.518] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:24.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:22:0) after 1m0s
[10:22:24.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:22:0)
[10:22:24.571] Timer tick, asking Buildbucket for the build status
[10:22:24.874] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:24.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:23:0) after 1m0s
[10:23:24.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:23:0)
[10:23:24.894] Timer tick, asking Buildbucket for the build status
[10:23:24.996] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:24.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:24:0) after 1m0s
[10:24:25.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:24:0)
[10:24:25.016] Timer tick, asking Buildbucket for the build status
[10:24:25.389] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:25.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:25:0) after 1m0s
[10:25:25.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:25:0)
[10:25:25.508] Timer tick, asking Buildbucket for the build status
[10:25:26.138] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:26.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:26:0) after 1m0s
[10:26:26.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:26:0)
[10:26:26.164] Timer tick, asking Buildbucket for the build status
[10:26:26.212] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:26.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:27:0) after 1m0s
[10:27:26.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:27:0)
[10:27:26.383] Timer tick, asking Buildbucket for the build status
[10:27:26.510] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:26.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:28:0) after 1m0s
[10:28:26.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:28:0)
[10:28:26.567] Timer tick, asking Buildbucket for the build status
[10:28:27.024] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:27.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:29:0) after 1m0s
[10:29:27.070] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:29:0)
[10:29:27.070] Timer tick, asking Buildbucket for the build status
[10:29:27.173] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:27.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:30:0) after 1m0s
[10:30:27.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:30:0)
[10:30:27.247] Timer tick, asking Buildbucket for the build status
[10:30:27.663] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:27.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:31:0) after 1m0s
[10:31:27.743] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:31:0)
[10:31:27.743] Timer tick, asking Buildbucket for the build status
[10:31:28.125] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:28.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:32:0) after 1m0s
[10:32:28.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:32:0)
[10:32:28.373] Timer tick, asking Buildbucket for the build status
[10:32:28.636] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:28.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:33:0) after 1m0s
[10:33:28.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:33:0)
[10:33:28.711] Timer tick, asking Buildbucket for the build status
[10:33:28.782] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:28.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:34:0) after 1m0s
[10:34:28.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:34:0)
[10:34:28.976] Timer tick, asking Buildbucket for the build status
[10:34:29.034] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:29.034] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:35:0) after 1m0s
[10:35:29.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:35:0)
[10:35:29.192] Timer tick, asking Buildbucket for the build status
[10:35:29.284] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:29.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:36:0) after 1m0s
[10:36:29.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:36:0)
[10:36:29.434] Timer tick, asking Buildbucket for the build status
[10:36:29.501] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:29.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:37:0) after 1m0s
[10:37:29.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:37:0)
[10:37:29.519] Timer tick, asking Buildbucket for the build status
[10:37:29.774] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:29.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:38:0) after 1m0s
[10:38:29.844] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:38:0)
[10:38:29.844] Timer tick, asking Buildbucket for the build status
[10:38:29.933] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:29.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:39:0) after 1m0s
[10:39:29.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:39:0)
[10:39:29.909] Timer tick, asking Buildbucket for the build status
[10:39:30.134] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:30.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:40:0) after 1m0s
[10:40:30.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:40:0)
[10:40:30.163] Timer tick, asking Buildbucket for the build status
[10:40:30.256] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:30.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:41:0) after 1m0s
[10:41:30.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:41:0)
[10:41:30.347] Timer tick, asking Buildbucket for the build status
[10:41:30.418] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:30.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:42:0) after 1m0s
[10:42:30.440] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:42:0)
[10:42:30.440] Timer tick, asking Buildbucket for the build status
[10:42:30.504] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:30.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:43:0) after 1m0s
[10:43:30.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:43:0)
[10:43:30.522] Timer tick, asking Buildbucket for the build status
[10:43:31.065] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:31.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:44:0) after 1m0s
[10:44:31.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:44:0)
[10:44:31.156] Timer tick, asking Buildbucket for the build status
[10:44:31.376] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:31.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:45:0) after 1m0s
[10:45:31.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:45:0)
[10:45:31.398] Timer tick, asking Buildbucket for the build status
[10:45:32.328] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:32.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:46:0) after 1m0s
[10:46:32.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:46:0)
[10:46:32.402] Timer tick, asking Buildbucket for the build status
[10:46:32.469] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:32.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:47:0) after 1m0s
[10:47:32.535] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:47:0)
[10:47:32.535] Timer tick, asking Buildbucket for the build status
[10:47:32.878] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:32.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:48:0) after 1m0s
[10:48:32.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:48:0)
[10:48:32.956] Timer tick, asking Buildbucket for the build status
[10:48:33.062] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:33.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:49:0) after 1m0s
[10:49:33.052] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:49:0)
[10:49:33.052] Timer tick, asking Buildbucket for the build status
[10:49:33.137] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:33.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:50:0) after 1m0s
[10:50:33.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:50:0)
[10:50:33.268] Timer tick, asking Buildbucket for the build status
[10:50:33.376] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:33.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:51:0) after 1m0s
[10:51:33.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:51:0)
[10:51:33.669] Timer tick, asking Buildbucket for the build status
[10:51:33.788] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:33.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:52:0) after 1m0s
[10:52:34.052] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:52:0)
[10:52:34.052] Timer tick, asking Buildbucket for the build status
[10:52:34.310] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:34.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:53:0) after 1m0s
[10:53:34.408] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:53:0)
[10:53:34.408] Timer tick, asking Buildbucket for the build status
[10:53:34.451] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:34.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:54:0) after 1m0s
[10:54:34.476] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:54:0)
[10:54:34.476] Timer tick, asking Buildbucket for the build status
[10:54:34.550] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:34.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:55:0) after 1m0s
[10:55:34.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:55:0)
[10:55:34.579] Timer tick, asking Buildbucket for the build status
[10:55:34.964] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:34.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:56:0) after 1m0s
[10:56:35.043] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:56:0)
[10:56:35.043] Timer tick, asking Buildbucket for the build status
[10:56:35.842] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:35.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:57:0) after 1m0s
[10:57:35.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:57:0)
[10:57:35.918] Timer tick, asking Buildbucket for the build status
[10:57:36.423] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:36.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:58:0) after 1m0s
[10:58:36.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:58:0)
[10:58:36.529] Timer tick, asking Buildbucket for the build status
[10:58:36.583] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:36.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:59:0) after 1m0s
[10:59:36.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:59:0)
[10:59:36.894] Timer tick, asking Buildbucket for the build status
[10:59:36.942] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:36.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:60:0) after 1m0s
[11:00:37.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:60:0)
[11:00:37.199] Timer tick, asking Buildbucket for the build status
[11:00:38.140] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:38.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:61:0) after 1m0s
[11:01:38.305] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:61:0)
[11:01:38.305] Timer tick, asking Buildbucket for the build status
[11:01:38.376] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:38.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:62:0) after 1m0s
[11:02:38.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:62:0)
[11:02:38.475] Timer tick, asking Buildbucket for the build status
[11:02:38.728] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:38.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:63:0) after 1m0s
[11:03:38.839] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:63:0)
[11:03:38.839] Timer tick, asking Buildbucket for the build status
[11:03:38.970] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:38.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:64:0) after 1m0s
[11:04:39.081] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:64:0)
[11:04:39.081] Timer tick, asking Buildbucket for the build status
[11:04:39.377] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:39.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:65:0) after 1m0s
[11:05:39.678] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:65:0)
[11:05:39.678] Timer tick, asking Buildbucket for the build status
[11:05:39.864] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:39.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:66:0) after 1m0s
[11:06:39.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:66:0)
[11:06:39.964] Timer tick, asking Buildbucket for the build status
[11:06:40.229] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:40.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:67:0) after 1m0s
[11:07:40.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:67:0)
[11:07:40.301] Timer tick, asking Buildbucket for the build status
[11:07:41.137] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:41.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:68:0) after 1m0s
[11:08:41.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:68:0)
[11:08:41.185] Timer tick, asking Buildbucket for the build status
[11:08:41.562] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:41.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:69:0) after 1m0s
[11:09:41.585] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:69:0)
[11:09:41.585] Timer tick, asking Buildbucket for the build status
[11:09:41.641] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:41.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:70:0) after 1m0s
[11:10:41.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:70:0)
[11:10:41.675] Timer tick, asking Buildbucket for the build status
[11:10:41.750] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:41.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:71:0) after 1m0s
[11:11:41.846] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:71:0)
[11:11:41.846] Timer tick, asking Buildbucket for the build status
[11:11:41.918] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:41.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:72:0) after 1m0s
[11:12:42.023] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:72:0)
[11:12:42.023] Timer tick, asking Buildbucket for the build status
[11:12:43.619] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:43.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:73:0) after 1m0s
[11:13:43.639] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:73:0)
[11:13:43.639] Timer tick, asking Buildbucket for the build status
[11:13:43.899] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:43.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:74:0) after 1m0s
[11:14:43.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:74:0)
[11:14:43.912] Timer tick, asking Buildbucket for the build status
[11:14:43.982] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:43.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:75:0) after 1m0s
[11:15:43.977] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:75:0)
[11:15:43.977] Timer tick, asking Buildbucket for the build status
[11:15:44.217] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:44.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:76:0) after 1m0s
[11:16:44.237] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:76:0)
[11:16:44.237] Timer tick, asking Buildbucket for the build status
[11:16:44.311] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:44.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:77:0) after 1m0s
[11:17:44.334] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:77:0)
[11:17:44.334] Timer tick, asking Buildbucket for the build status
[11:17:44.432] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:44.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:78:0) after 1m0s
[11:18:44.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:78:0)
[11:18:44.509] Timer tick, asking Buildbucket for the build status
[11:18:44.569] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:44.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:79:0) after 1m0s
[11:19:44.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:79:0)
[11:19:44.559] Timer tick, asking Buildbucket for the build status
[11:19:44.772] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:44.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:80:0) after 1m0s
[11:20:44.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:80:0)
[11:20:44.760] Timer tick, asking Buildbucket for the build status
[11:20:44.833] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:44.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:81:0) after 1m0s
[11:21:44.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:81:0)
[11:21:44.955] Timer tick, asking Buildbucket for the build status
[11:21:45.258] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:45.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:82:0) after 1m0s
[11:22:45.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:82:0)
[11:22:45.314] Timer tick, asking Buildbucket for the build status
[11:22:45.368] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:45.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:83:0) after 1m0s
[11:23:45.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:83:0)
[11:23:45.486] Timer tick, asking Buildbucket for the build status
[11:23:45.905] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:45.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:84:0) after 1m0s
[11:24:45.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:84:0)
[11:24:45.898] Timer tick, asking Buildbucket for the build status
[11:24:46.062] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:46.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:85:0) after 1m0s
[11:25:46.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:85:0)
[11:25:46.124] Timer tick, asking Buildbucket for the build status
[11:25:46.831] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:46.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:86:0) after 1m0s
[11:26:46.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:86:0)
[11:26:46.995] Timer tick, asking Buildbucket for the build status
[11:26:47.199] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:47.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:87:0) after 1m0s
[11:27:47.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:87:0)
[11:27:47.326] Timer tick, asking Buildbucket for the build status
[11:27:47.374] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:47.374] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:88:0) after 1m0s
[11:28:47.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:88:0)
[11:28:47.469] Timer tick, asking Buildbucket for the build status
[11:28:47.515] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:47.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:89:0) after 1m0s
[11:29:47.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:89:0)
[11:29:47.697] Timer tick, asking Buildbucket for the build status
[11:29:47.963] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:47.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:90:0) after 1m0s
[11:30:48.048] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:90:0)
[11:30:48.048] Timer tick, asking Buildbucket for the build status
[11:30:48.187] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:48.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:91:0) after 1m0s
[11:31:48.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:91:0)
[11:31:48.351] Timer tick, asking Buildbucket for the build status
[11:31:48.439] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:48.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:92:0) after 1m0s
[11:32:48.545] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:92:0)
[11:32:48.545] Timer tick, asking Buildbucket for the build status
[11:32:48.868] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:48.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:93:0) after 1m0s
[11:33:48.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:93:0)
[11:33:48.888] Timer tick, asking Buildbucket for the build status
[11:33:48.974] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:48.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:94:0) after 1m0s
[11:34:49.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:94:0)
[11:34:49.009] Timer tick, asking Buildbucket for the build status
[11:34:49.291] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:49.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:95:0) after 1m0s
[11:35:49.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:95:0)
[11:35:49.315] Timer tick, asking Buildbucket for the build status
[11:35:49.420] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:49.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:96:0) after 1m0s
[11:36:49.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:96:0)
[11:36:49.448] Timer tick, asking Buildbucket for the build status
[11:36:49.558] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:49.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:97:0) after 1m0s
[11:37:49.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:97:0)
[11:37:49.605] Timer tick, asking Buildbucket for the build status
[11:37:49.669] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:49.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:98:0) after 1m0s
[11:38:49.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:98:0)
[11:38:49.815] Timer tick, asking Buildbucket for the build status
[11:38:50.064] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:50.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:99:0) after 1m0s
[11:39:50.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:99:0)
[11:39:50.172] Timer tick, asking Buildbucket for the build status
[11:39:50.266] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:50.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:100:0) after 1m0s
[11:40:50.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:100:0)
[11:40:50.287] Timer tick, asking Buildbucket for the build status
[11:40:50.583] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:50.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:101:0) after 1m0s
[11:41:50.647] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:101:0)
[11:41:50.647] Timer tick, asking Buildbucket for the build status
[11:41:50.695] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:50.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:102:0) after 1m0s
[11:42:50.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:102:0)
[11:42:50.781] Timer tick, asking Buildbucket for the build status
[11:42:50.829] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:50.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:103:0) after 1m0s
[11:43:50.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:103:0)
[11:43:50.866] Timer tick, asking Buildbucket for the build status
[11:43:50.912] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:50.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:104:0) after 1m0s
[11:44:50.941] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:104:0)
[11:44:50.941] Timer tick, asking Buildbucket for the build status
[11:44:51.061] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:51.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:105:0) after 1m0s
[11:45:51.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:105:0)
[11:45:51.218] Timer tick, asking Buildbucket for the build status
[11:45:51.452] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:51.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:106:0) after 1m0s
[11:46:51.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:106:0)
[11:46:51.524] Timer tick, asking Buildbucket for the build status
[11:46:51.574] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:51.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:107:0) after 1m0s
[11:47:51.652] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:107:0)
[11:47:51.652] Timer tick, asking Buildbucket for the build status
[11:47:52.050] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:52.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:108:0) after 1m0s
[11:48:52.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:108:0)
[11:48:52.074] Timer tick, asking Buildbucket for the build status
[11:48:52.179] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:52.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:109:0) after 1m0s
[11:49:52.258] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:109:0)
[11:49:52.258] Timer tick, asking Buildbucket for the build status
[11:49:52.358] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:52.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:110:0) after 1m0s
[11:50:52.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:110:0)
[11:50:52.431] Timer tick, asking Buildbucket for the build status
[11:50:52.482] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:52.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:111:0) after 1m0s
[11:51:52.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:111:0)
[11:51:52.511] Timer tick, asking Buildbucket for the build status
[11:51:52.583] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:52.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:112:0) after 1m0s
[11:52:52.613] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:112:0)
[11:52:52.613] Timer tick, asking Buildbucket for the build status
[11:52:53.235] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:53.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:113:0) after 1m0s
[11:53:53.254] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:113:0)
[11:53:53.254] Timer tick, asking Buildbucket for the build status
[11:53:53.509] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:53.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:114:0) after 1m0s
[11:54:53.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:114:0)
[11:54:53.530] Timer tick, asking Buildbucket for the build status
[11:54:53.643] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:53.643] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:115:0) after 1m0s
[11:55:53.667] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:115:0)
[11:55:53.667] Timer tick, asking Buildbucket for the build status
[11:55:53.734] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:53.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:116:0) after 1m0s
[11:56:53.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:116:0)
[11:56:53.866] Timer tick, asking Buildbucket for the build status
[11:56:54.111] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:54.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:117:0) after 1m0s
[11:57:54.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:117:0)
[11:57:54.148] Timer tick, asking Buildbucket for the build status
[11:57:54.257] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:54.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:118:0) after 1m0s
[11:58:54.510] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:118:0)
[11:58:54.510] Timer tick, asking Buildbucket for the build status
[11:58:54.654] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:54.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:119:0) after 1m0s
[11:59:54.863] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:119:0)
[11:59:54.863] Timer tick, asking Buildbucket for the build status
[11:59:55.170] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:55.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:120:0) after 1m0s
[12:00:55.190] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:120:0)
[12:00:55.190] Timer tick, asking Buildbucket for the build status
[12:00:55.370] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:55.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:121:0) after 1m0s
[12:01:55.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:121:0)
[12:01:55.391] Timer tick, asking Buildbucket for the build status
[12:01:55.454] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:55.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:122:0) after 1m0s
[12:02:56.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:122:0)
[12:02:56.123] Timer tick, asking Buildbucket for the build status
[12:02:56.468] Build 8912042969084351616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:56.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077473032788482672:123:0) after 1m0s
[12:02:58.822] Received PubSub notification, asking Buildbucket for the build status
[12:02:58.894] Build 8912042969084351616: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[12:02:58.894] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1559217777851594",
"created_by": "project:fuchsia",
"created_ts": "1559210535883289",
"failure_reason": "INFRA_FAILURE",
"id": "8912042969084351616",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"fa147c97d6ba1a59fe885a193a40a274158f38da\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"recipe infra failure: Infra Failure: Step('buildbucket.run.collect') (timeout)\"}, \"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-prod-1-us-central1-b-0-qazs\", \"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\": \"fa147c97d6ba1a59fe885a193a40a274158f38da\", \"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-prod-1-us-central1-b-0-qazs\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"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\": [\"4316-effbcfc\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"recipe infra failure: Infra Failure: Step('buildbucket.run.collect') (timeout)\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1559210559733673",
"status": "COMPLETED",
"status_changed_ts": "1559210561526166",
"tags": [
"builder:clang",
"buildset:commit/git/fa147c97d6ba1a59fe885a193a40a274158f38da",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/fa147c97d6ba1a59fe885a193a40a274158f38da",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077473032788482672",
"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/8912042969084351616/+/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:45210237dacb8310",
"user_agent:luci-scheduler"
],
"updated_ts": "1559217778187333",
"url": "https://ci.chromium.org/b/8912042969084351616",
"utcnow_ts": "1559217778860455"
}
}
[12:02:58.894] Invocation finished in 2h0m45.926917718s with status FAILED