[13:50:30.448] New invocation is queued and will start shortly
[13:50:31.482] Starting the invocation (attempt 1)
[13:50:31.512] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:50:31.512] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:50:31.512] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078002251755656224",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"7991b6828484ec6deb466809a36f7ab7b0af90ce\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078002251755656224",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/7991b6828484ec6deb466809a36f7ab7b0af90ce",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7991b6828484ec6deb466809a36f7ab7b0af90ce",
"gitiles_ref:refs/heads/master"
]
}
[13:50:32.021] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558705831611243",
"id": "8912572189871301888",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7991b6828484ec6deb466809a36f7ab7b0af90ce\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558705831909579",
"tags": [
"builder:clang",
"buildset:commit/git/7991b6828484ec6deb466809a36f7ab7b0af90ce",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7991b6828484ec6deb466809a36f7ab7b0af90ce",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078002251755656224",
"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/8912572189871301888/+/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": "1558705831909670",
"url": "https://ci.chromium.org/b/8912572189871301888",
"utcnow_ts": "1558705832014702"
}
}
[13:50:32.021] Task URL: https://ci.chromium.org/b/8912572189871301888
[13:50:32.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:2:0) after 1m0s
[13:50:48.649] Received PubSub notification, asking Buildbucket for the build status
[13:50:48.987] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:32.054] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:2:0)
[13:51:32.054] Timer tick, asking Buildbucket for the build status
[13:51:32.166] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:32.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:4:0) after 1m0s
[13:52:32.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:4:0)
[13:52:32.241] Timer tick, asking Buildbucket for the build status
[13:52:32.374] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:32.374] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:5:0) after 1m0s
[13:53:32.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:5:0)
[13:53:32.394] Timer tick, asking Buildbucket for the build status
[13:53:32.435] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:32.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:6:0) after 1m0s
[13:54:32.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:6:0)
[13:54:32.485] Timer tick, asking Buildbucket for the build status
[13:54:33.145] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:33.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:7:0) after 1m0s
[13:55:34.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:7:0)
[13:55:34.028] Timer tick, asking Buildbucket for the build status
[13:55:34.568] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:34.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:8:0) after 1m0s
[13:56:34.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:8:0)
[13:56:34.650] Timer tick, asking Buildbucket for the build status
[13:56:34.868] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:34.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:9:0) after 1m0s
[13:57:35.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:9:0)
[13:57:35.095] Timer tick, asking Buildbucket for the build status
[13:57:35.222] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:35.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:10:0) after 1m0s
[13:58:35.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:10:0)
[13:58:35.248] Timer tick, asking Buildbucket for the build status
[13:58:35.344] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:35.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:11:0) after 1m0s
[13:59:35.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:11:0)
[13:59:35.342] Timer tick, asking Buildbucket for the build status
[13:59:35.654] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:35.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:12:0) after 1m0s
[14:00:35.691] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:12:0)
[14:00:35.691] Timer tick, asking Buildbucket for the build status
[14:00:35.797] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:35.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:13:0) after 1m0s
[14:01:35.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:13:0)
[14:01:35.807] Timer tick, asking Buildbucket for the build status
[14:01:35.901] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:35.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:14:0) after 1m0s
[14:02:35.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:14:0)
[14:02:35.968] Timer tick, asking Buildbucket for the build status
[14:02:36.073] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:36.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:15:0) after 1m0s
[14:03:36.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:15:0)
[14:03:36.287] Timer tick, asking Buildbucket for the build status
[14:03:36.489] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:36.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:16:0) after 1m0s
[14:04:36.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:16:0)
[14:04:36.531] Timer tick, asking Buildbucket for the build status
[14:04:36.680] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:36.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:17:0) after 1m0s
[14:05:36.740] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:17:0)
[14:05:36.740] Timer tick, asking Buildbucket for the build status
[14:05:36.928] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:36.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:18:0) after 1m0s
[14:06:36.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:18:0)
[14:06:36.917] Timer tick, asking Buildbucket for the build status
[14:06:37.033] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:37.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:19:0) after 1m0s
[14:07:37.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:19:0)
[14:07:37.145] Timer tick, asking Buildbucket for the build status
[14:07:37.540] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:37.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:20:0) after 1m0s
[14:08:37.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:20:0)
[14:08:37.542] Timer tick, asking Buildbucket for the build status
[14:08:37.618] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:37.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:21:0) after 1m0s
[14:09:37.643] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:21:0)
[14:09:37.643] Timer tick, asking Buildbucket for the build status
[14:09:37.794] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:37.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:22:0) after 1m0s
[14:10:37.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:22:0)
[14:10:37.880] Timer tick, asking Buildbucket for the build status
[14:10:38.389] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:38.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:23:0) after 1m0s
[14:11:38.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:23:0)
[14:11:38.448] Timer tick, asking Buildbucket for the build status
[14:11:38.550] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:38.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:24:0) after 1m0s
[14:12:38.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:24:0)
[14:12:38.582] Timer tick, asking Buildbucket for the build status
[14:12:39.114] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:39.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:25:0) after 1m0s
[14:13:39.140] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:25:0)
[14:13:39.140] Timer tick, asking Buildbucket for the build status
[14:13:39.874] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:39.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:26:0) after 1m0s
[14:14:39.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:26:0)
[14:14:39.874] Timer tick, asking Buildbucket for the build status
[14:14:40.028] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:40.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:27:0) after 1m0s
[14:15:40.233] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:27:0)
[14:15:40.233] Timer tick, asking Buildbucket for the build status
[14:15:40.567] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:40.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:28:0) after 1m0s
[14:16:40.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:28:0)
[14:16:40.610] Timer tick, asking Buildbucket for the build status
[14:16:40.663] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:40.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:29:0) after 1m0s
[14:17:40.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:29:0)
[14:17:40.693] Timer tick, asking Buildbucket for the build status
[14:17:40.808] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:40.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:30:0) after 1m0s
[14:18:40.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:30:0)
[14:18:40.838] Timer tick, asking Buildbucket for the build status
[14:18:40.922] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:40.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:31:0) after 1m0s
[14:19:40.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:31:0)
[14:19:40.956] Timer tick, asking Buildbucket for the build status
[14:19:41.003] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:41.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:32:0) after 1m0s
[14:20:41.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:32:0)
[14:20:41.061] Timer tick, asking Buildbucket for the build status
[14:20:41.910] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:41.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:33:0) after 1m0s
[14:21:41.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:33:0)
[14:21:41.922] Timer tick, asking Buildbucket for the build status
[14:21:42.184] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:42.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:34:0) after 1m0s
[14:22:42.288] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:34:0)
[14:22:42.288] Timer tick, asking Buildbucket for the build status
[14:22:42.612] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:42.612] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:35:0) after 1m0s
[14:23:42.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:35:0)
[14:23:42.989] Timer tick, asking Buildbucket for the build status
[14:23:43.032] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:43.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:36:0) after 1m0s
[14:24:43.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:36:0)
[14:24:43.097] Timer tick, asking Buildbucket for the build status
[14:24:43.151] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:43.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:37:0) after 1m0s
[14:25:43.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:37:0)
[14:25:43.169] Timer tick, asking Buildbucket for the build status
[14:25:43.213] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:43.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:38:0) after 1m0s
[14:26:43.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:38:0)
[14:26:43.204] Timer tick, asking Buildbucket for the build status
[14:26:43.262] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:43.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:39:0) after 1m0s
[14:27:43.320] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:39:0)
[14:27:43.320] Timer tick, asking Buildbucket for the build status
[14:27:43.356] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:43.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:40:0) after 1m0s
[14:28:43.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:40:0)
[14:28:43.412] Timer tick, asking Buildbucket for the build status
[14:28:43.817] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:43.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:41:0) after 1m0s
[14:29:43.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:41:0)
[14:29:43.871] Timer tick, asking Buildbucket for the build status
[14:29:44.292] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:44.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:42:0) after 1m0s
[14:30:44.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:42:0)
[14:30:44.364] Timer tick, asking Buildbucket for the build status
[14:30:44.498] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:44.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:43:0) after 1m0s
[14:31:44.550] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:43:0)
[14:31:44.550] Timer tick, asking Buildbucket for the build status
[14:31:44.672] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:44.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:44:0) after 1m0s
[14:32:44.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:44:0)
[14:32:44.705] Timer tick, asking Buildbucket for the build status
[14:32:45.212] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:45.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:45:0) after 1m0s
[14:33:45.233] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:45:0)
[14:33:45.233] Timer tick, asking Buildbucket for the build status
[14:33:45.361] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:45.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:46:0) after 1m0s
[14:34:45.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:46:0)
[14:34:45.453] Timer tick, asking Buildbucket for the build status
[14:34:46.084] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:46.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:47:0) after 1m0s
[14:35:46.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:47:0)
[14:35:46.116] Timer tick, asking Buildbucket for the build status
[14:35:46.671] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:46.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:48:0) after 1m0s
[14:36:46.665] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:48:0)
[14:36:46.665] Timer tick, asking Buildbucket for the build status
[14:36:46.781] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:46.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:49:0) after 1m0s
[14:37:46.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:49:0)
[14:37:46.896] Timer tick, asking Buildbucket for the build status
[14:37:47.086] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:47.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:50:0) after 1m0s
[14:38:47.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:50:0)
[14:38:47.172] Timer tick, asking Buildbucket for the build status
[14:38:47.239] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:47.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:51:0) after 1m0s
[14:39:47.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:51:0)
[14:39:47.368] Timer tick, asking Buildbucket for the build status
[14:39:47.553] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:47.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:52:0) after 1m0s
[14:40:47.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:52:0)
[14:40:47.689] Timer tick, asking Buildbucket for the build status
[14:40:47.742] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:47.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:53:0) after 1m0s
[14:41:47.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:53:0)
[14:41:47.755] Timer tick, asking Buildbucket for the build status
[14:41:47.800] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:47.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:54:0) after 1m0s
[14:42:48.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:54:0)
[14:42:48.274] Timer tick, asking Buildbucket for the build status
[14:42:48.314] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:48.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:55:0) after 1m0s
[14:43:48.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:55:0)
[14:43:48.373] Timer tick, asking Buildbucket for the build status
[14:43:48.445] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:48.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:56:0) after 1m0s
[14:44:48.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:56:0)
[14:44:48.496] Timer tick, asking Buildbucket for the build status
[14:44:48.551] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:48.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:57:0) after 1m0s
[14:45:48.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:57:0)
[14:45:48.589] Timer tick, asking Buildbucket for the build status
[14:45:49.014] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:49.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:58:0) after 1m0s
[14:46:49.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:58:0)
[14:46:49.117] Timer tick, asking Buildbucket for the build status
[14:46:49.170] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:49.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:59:0) after 1m0s
[14:47:49.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:59:0)
[14:47:49.169] Timer tick, asking Buildbucket for the build status
[14:47:49.258] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:49.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:60:0) after 1m0s
[14:48:49.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:60:0)
[14:48:49.280] Timer tick, asking Buildbucket for the build status
[14:48:49.477] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:49.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:61:0) after 1m0s
[14:49:49.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:61:0)
[14:49:49.536] Timer tick, asking Buildbucket for the build status
[14:49:49.659] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:49.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:62:0) after 1m0s
[14:50:49.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:62:0)
[14:50:49.684] Timer tick, asking Buildbucket for the build status
[14:50:49.980] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:49.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:63:0) after 1m0s
[14:51:50.032] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:63:0)
[14:51:50.032] Timer tick, asking Buildbucket for the build status
[14:51:50.083] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:50.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:64:0) after 1m0s
[14:52:50.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:64:0)
[14:52:50.166] Timer tick, asking Buildbucket for the build status
[14:52:50.237] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:50.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:65:0) after 1m0s
[14:53:50.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:65:0)
[14:53:50.346] Timer tick, asking Buildbucket for the build status
[14:53:50.522] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:50.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:66:0) after 1m0s
[14:54:50.629] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:66:0)
[14:54:50.629] Timer tick, asking Buildbucket for the build status
[14:54:50.708] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:50.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:67:0) after 1m0s
[14:55:50.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:67:0)
[14:55:50.910] Timer tick, asking Buildbucket for the build status
[14:55:51.085] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:51.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:68:0) after 1m0s
[14:56:51.313] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:68:0)
[14:56:51.313] Timer tick, asking Buildbucket for the build status
[14:56:51.405] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:51.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:69:0) after 1m0s
[14:57:51.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:69:0)
[14:57:51.515] Timer tick, asking Buildbucket for the build status
[14:57:51.636] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:51.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:70:0) after 1m0s
[14:58:51.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:70:0)
[14:58:51.736] Timer tick, asking Buildbucket for the build status
[14:58:51.781] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:51.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:71:0) after 1m0s
[14:59:51.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:71:0)
[14:59:51.853] Timer tick, asking Buildbucket for the build status
[14:59:52.046] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:52.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:72:0) after 1m0s
[15:00:52.160] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:72:0)
[15:00:52.160] Timer tick, asking Buildbucket for the build status
[15:00:52.517] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:52.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:73:0) after 1m0s
[15:01:52.680] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:73:0)
[15:01:52.680] Timer tick, asking Buildbucket for the build status
[15:01:52.864] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:52.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:74:0) after 1m0s
[15:02:52.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:74:0)
[15:02:52.938] Timer tick, asking Buildbucket for the build status
[15:02:53.094] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:53.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:75:0) after 1m0s
[15:03:53.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:75:0)
[15:03:53.534] Timer tick, asking Buildbucket for the build status
[15:03:54.134] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:54.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:76:0) after 1m0s
[15:04:54.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:76:0)
[15:04:54.189] Timer tick, asking Buildbucket for the build status
[15:04:54.407] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:54.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:77:0) after 1m0s
[15:05:54.494] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:77:0)
[15:05:54.494] Timer tick, asking Buildbucket for the build status
[15:05:55.083] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:55.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:78:0) after 1m0s
[15:06:55.108] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:78:0)
[15:06:55.108] Timer tick, asking Buildbucket for the build status
[15:06:55.193] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:55.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:79:0) after 1m0s
[15:07:55.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:79:0)
[15:07:55.289] Timer tick, asking Buildbucket for the build status
[15:07:55.353] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:55.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:80:0) after 1m0s
[15:08:55.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:80:0)
[15:08:55.488] Timer tick, asking Buildbucket for the build status
[15:08:55.935] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:55.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:81:0) after 1m0s
[15:09:56.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:81:0)
[15:09:56.111] Timer tick, asking Buildbucket for the build status
[15:09:56.385] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:56.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:82:0) after 1m0s
[15:10:56.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:82:0)
[15:10:56.443] Timer tick, asking Buildbucket for the build status
[15:10:56.614] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:56.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:83:0) after 1m0s
[15:11:56.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:83:0)
[15:11:56.684] Timer tick, asking Buildbucket for the build status
[15:11:56.949] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:56.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:84:0) after 1m0s
[15:12:57.030] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:84:0)
[15:12:57.030] Timer tick, asking Buildbucket for the build status
[15:12:57.276] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:57.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:85:0) after 1m0s
[15:13:57.335] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:85:0)
[15:13:57.335] Timer tick, asking Buildbucket for the build status
[15:13:57.597] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:57.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:86:0) after 1m0s
[15:14:57.733] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:86:0)
[15:14:57.733] Timer tick, asking Buildbucket for the build status
[15:14:57.935] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:57.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:87:0) after 1m0s
[15:15:58.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:87:0)
[15:15:58.224] Timer tick, asking Buildbucket for the build status
[15:15:58.302] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:58.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:88:0) after 1m0s
[15:16:58.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:88:0)
[15:16:58.433] Timer tick, asking Buildbucket for the build status
[15:16:58.527] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:58.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:89:0) after 1m0s
[15:17:58.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:89:0)
[15:17:58.608] Timer tick, asking Buildbucket for the build status
[15:17:58.733] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:58.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:90:0) after 1m0s
[15:18:58.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:90:0)
[15:18:58.757] Timer tick, asking Buildbucket for the build status
[15:18:58.811] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:58.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:91:0) after 1m0s
[15:19:58.841] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:91:0)
[15:19:58.841] Timer tick, asking Buildbucket for the build status
[15:19:58.918] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:58.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:92:0) after 1m0s
[15:20:58.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:92:0)
[15:20:58.933] Timer tick, asking Buildbucket for the build status
[15:20:59.534] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:59.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:93:0) after 1m0s
[15:21:59.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:93:0)
[15:21:59.531] Timer tick, asking Buildbucket for the build status
[15:21:59.582] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:59.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:94:0) after 1m0s
[15:22:59.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:94:0)
[15:22:59.595] Timer tick, asking Buildbucket for the build status
[15:22:59.653] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:59.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:95:0) after 1m0s
[15:23:59.723] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:95:0)
[15:23:59.723] Timer tick, asking Buildbucket for the build status
[15:23:59.980] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:59.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:96:0) after 1m0s
[15:25:00.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:96:0)
[15:25:00.097] Timer tick, asking Buildbucket for the build status
[15:25:01.176] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:01.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:97:0) after 1m0s
[15:26:01.220] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:97:0)
[15:26:01.220] Timer tick, asking Buildbucket for the build status
[15:26:01.425] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:01.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:98:0) after 1m0s
[15:27:01.447] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:98:0)
[15:27:01.447] Timer tick, asking Buildbucket for the build status
[15:27:01.897] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:01.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:99:0) after 1m0s
[15:28:01.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:99:0)
[15:28:01.927] Timer tick, asking Buildbucket for the build status
[15:28:02.271] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:02.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:100:0) after 1m0s
[15:29:02.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:100:0)
[15:29:02.328] Timer tick, asking Buildbucket for the build status
[15:29:02.456] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:02.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:101:0) after 1m0s
[15:30:02.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:101:0)
[15:30:02.564] Timer tick, asking Buildbucket for the build status
[15:30:02.697] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:02.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:102:0) after 1m0s
[15:31:02.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:102:0)
[15:31:02.685] Timer tick, asking Buildbucket for the build status
[15:31:02.725] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:02.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:103:0) after 1m0s
[15:32:02.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:103:0)
[15:32:02.779] Timer tick, asking Buildbucket for the build status
[15:32:04.066] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:04.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:104:0) after 1m0s
[15:33:04.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:104:0)
[15:33:04.065] Timer tick, asking Buildbucket for the build status
[15:33:04.161] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:04.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:105:0) after 1m0s
[15:34:04.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:105:0)
[15:34:04.216] Timer tick, asking Buildbucket for the build status
[15:34:04.380] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:04.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:106:0) after 1m0s
[15:35:04.357] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:106:0)
[15:35:04.357] Timer tick, asking Buildbucket for the build status
[15:35:04.557] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:04.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:107:0) after 1m0s
[15:36:04.637] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:107:0)
[15:36:04.637] Timer tick, asking Buildbucket for the build status
[15:36:04.733] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:04.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:108:0) after 1m0s
[15:37:04.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:108:0)
[15:37:04.776] Timer tick, asking Buildbucket for the build status
[15:37:04.857] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:04.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:109:0) after 1m0s
[15:38:05.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:109:0)
[15:38:05.000] Timer tick, asking Buildbucket for the build status
[15:38:05.128] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:05.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:110:0) after 1m0s
[15:39:05.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:110:0)
[15:39:05.223] Timer tick, asking Buildbucket for the build status
[15:39:05.454] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:05.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:111:0) after 1m0s
[15:40:05.550] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:111:0)
[15:40:05.550] Timer tick, asking Buildbucket for the build status
[15:40:05.772] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:05.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:112:0) after 1m0s
[15:41:05.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:112:0)
[15:41:05.756] Timer tick, asking Buildbucket for the build status
[15:41:05.802] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:05.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:113:0) after 1m0s
[15:42:05.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:113:0)
[15:42:05.836] Timer tick, asking Buildbucket for the build status
[15:42:05.922] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:05.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:114:0) after 1m0s
[15:43:06.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:114:0)
[15:43:06.019] Timer tick, asking Buildbucket for the build status
[15:43:06.141] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:06.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:115:0) after 1m0s
[15:44:06.253] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:115:0)
[15:44:06.253] Timer tick, asking Buildbucket for the build status
[15:44:06.304] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:06.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:116:0) after 1m0s
[15:45:06.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:116:0)
[15:45:06.463] Timer tick, asking Buildbucket for the build status
[15:45:06.735] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:06.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:117:0) after 1m0s
[15:46:07.109] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:117:0)
[15:46:07.109] Timer tick, asking Buildbucket for the build status
[15:46:07.202] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:07.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:118:0) after 1m0s
[15:47:07.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:118:0)
[15:47:07.269] Timer tick, asking Buildbucket for the build status
[15:47:08.046] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:08.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:119:0) after 1m0s
[15:48:08.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:119:0)
[15:48:08.192] Timer tick, asking Buildbucket for the build status
[15:48:08.312] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:08.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:120:0) after 1m0s
[15:49:08.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:120:0)
[15:49:08.423] Timer tick, asking Buildbucket for the build status
[15:49:08.532] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:08.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:121:0) after 1m0s
[15:50:08.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:121:0)
[15:50:08.794] Timer tick, asking Buildbucket for the build status
[15:50:08.880] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:08.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:122:0) after 1m0s
[15:51:08.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:122:0)
[15:51:08.956] Timer tick, asking Buildbucket for the build status
[15:51:09.095] Build 8912572189871301888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:09.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078002251755656224:123:0) after 1m0s
[15:51:13.304] Received PubSub notification, asking Buildbucket for the build status
[15:51:13.573] Build 8912572189871301888: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:51:13.574] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558713072290927",
"created_by": "project:fuchsia",
"created_ts": "1558705831611243",
"failure_reason": "BUILD_FAILURE",
"id": "8912572189871301888",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"7991b6828484ec6deb466809a36f7ab7b0af90ce\"}}",
"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-prod-1-us-central1-c-1-ccvl\", \"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\": \"7991b6828484ec6deb466809a36f7ab7b0af90ce\", \"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-c-1-ccvl\"], \"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\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1558705845699142",
"status": "COMPLETED",
"status_changed_ts": "1558705846613791",
"tags": [
"builder:clang",
"buildset:commit/git/7991b6828484ec6deb466809a36f7ab7b0af90ce",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/7991b6828484ec6deb466809a36f7ab7b0af90ce",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078002251755656224",
"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/8912572189871301888/+/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:4502ed0d8eea2410",
"user_agent:luci-scheduler"
],
"updated_ts": "1558713072601706",
"url": "https://ci.chromium.org/b/8912572189871301888",
"utcnow_ts": "1558713073553135"
}
}
[15:51:13.574] Invocation finished in 2h0m43.143351127s with status FAILED