[04:46:44.577] New invocation is queued and will start shortly
[04:46:45.709] Starting the invocation (attempt 1)
[04:46:45.753] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:46:45.753] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:46:45.754] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083200489553356224",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"24fa56bcc8b9e61f450cc5c12d8c6da6dabf782d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083200489553356224",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/24fa56bcc8b9e61f450cc5c12d8c6da6dabf782d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/24fa56bcc8b9e61f450cc5c12d8c6da6dabf782d",
"gitiles_ref:refs/heads/master"
]
}
[04:46:46.940] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553748405878965",
"id": "8917770427516691200",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"24fa56bcc8b9e61f450cc5c12d8c6da6dabf782d\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553748406669292",
"tags": [
"builder:clang",
"buildset:commit/git/24fa56bcc8b9e61f450cc5c12d8c6da6dabf782d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/24fa56bcc8b9e61f450cc5c12d8c6da6dabf782d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083200489553356224",
"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/8917770427516691200/+/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": "1553748406669434",
"url": "https://ci.chromium.org/b/8917770427516691200",
"utcnow_ts": "1553748406891833"
}
}
[04:46:46.940] Task URL: https://ci.chromium.org/b/8917770427516691200
[04:46:46.940] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:2:0) after 1m0s
[04:46:59.531] Received PubSub notification, asking Buildbucket for the build status
[04:46:59.638] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:46.957] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:2:0)
[04:47:46.957] Timer tick, asking Buildbucket for the build status
[04:47:47.037] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:47.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:4:0) after 1m0s
[04:48:47.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:4:0)
[04:48:47.053] Timer tick, asking Buildbucket for the build status
[04:48:47.220] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:47.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:5:0) after 1m0s
[04:49:47.235] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:5:0)
[04:49:47.235] Timer tick, asking Buildbucket for the build status
[04:49:47.297] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:47.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:6:0) after 1m0s
[04:50:47.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:6:0)
[04:50:47.318] Timer tick, asking Buildbucket for the build status
[04:50:47.398] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:47.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:7:0) after 1m0s
[04:51:47.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:7:0)
[04:51:47.399] Timer tick, asking Buildbucket for the build status
[04:51:47.442] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:47.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:8:0) after 1m0s
[04:52:47.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:8:0)
[04:52:47.426] Timer tick, asking Buildbucket for the build status
[04:52:47.578] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:47.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:9:0) after 1m0s
[04:53:47.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:9:0)
[04:53:47.599] Timer tick, asking Buildbucket for the build status
[04:53:47.644] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:47.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:10:0) after 1m0s
[04:54:47.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:10:0)
[04:54:47.661] Timer tick, asking Buildbucket for the build status
[04:54:47.782] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:47.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:11:0) after 1m0s
[04:55:47.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:11:0)
[04:55:47.781] Timer tick, asking Buildbucket for the build status
[04:55:47.821] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:47.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:12:0) after 1m0s
[04:56:47.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:12:0)
[04:56:47.798] Timer tick, asking Buildbucket for the build status
[04:56:47.872] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:47.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:13:0) after 1m0s
[04:57:47.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:13:0)
[04:57:47.880] Timer tick, asking Buildbucket for the build status
[04:57:47.921] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:47.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:14:0) after 1m0s
[04:58:47.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:14:0)
[04:58:47.943] Timer tick, asking Buildbucket for the build status
[04:58:48.069] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:48.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:15:0) after 1m0s
[04:59:48.046] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:15:0)
[04:59:48.046] Timer tick, asking Buildbucket for the build status
[04:59:48.135] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:48.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:16:0) after 1m0s
[05:00:48.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:16:0)
[05:00:48.153] Timer tick, asking Buildbucket for the build status
[05:00:48.228] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:48.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:17:0) after 1m0s
[05:01:48.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:17:0)
[05:01:48.303] Timer tick, asking Buildbucket for the build status
[05:01:48.378] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:48.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:18:0) after 1m0s
[05:02:48.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:18:0)
[05:02:48.397] Timer tick, asking Buildbucket for the build status
[05:02:48.557] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:48.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:19:0) after 1m0s
[05:03:48.575] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:19:0)
[05:03:48.575] Timer tick, asking Buildbucket for the build status
[05:03:48.615] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:48.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:20:0) after 1m0s
[05:04:48.636] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:20:0)
[05:04:48.636] Timer tick, asking Buildbucket for the build status
[05:04:48.706] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:48.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:21:0) after 1m0s
[05:05:48.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:21:0)
[05:05:48.730] Timer tick, asking Buildbucket for the build status
[05:05:48.770] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:48.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:22:0) after 1m0s
[05:06:48.790] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:22:0)
[05:06:48.790] Timer tick, asking Buildbucket for the build status
[05:06:49.005] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:49.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:23:0) after 1m0s
[05:07:49.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:23:0)
[05:07:49.027] Timer tick, asking Buildbucket for the build status
[05:07:49.129] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:49.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:24:0) after 1m0s
[05:08:49.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:24:0)
[05:08:49.111] Timer tick, asking Buildbucket for the build status
[05:08:49.164] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:49.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:25:0) after 1m0s
[05:09:49.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:25:0)
[05:09:49.181] Timer tick, asking Buildbucket for the build status
[05:09:49.242] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:49.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:26:0) after 1m0s
[05:10:49.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:26:0)
[05:10:49.259] Timer tick, asking Buildbucket for the build status
[05:10:49.404] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:49.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:27:0) after 1m0s
[05:11:49.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:27:0)
[05:11:49.453] Timer tick, asking Buildbucket for the build status
[05:11:49.523] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:49.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:28:0) after 1m0s
[05:12:49.541] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:28:0)
[05:12:49.541] Timer tick, asking Buildbucket for the build status
[05:12:49.607] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:49.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:29:0) after 1m0s
[05:13:49.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:29:0)
[05:13:49.627] Timer tick, asking Buildbucket for the build status
[05:13:49.668] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:49.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:30:0) after 1m0s
[05:14:49.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:30:0)
[05:14:49.654] Timer tick, asking Buildbucket for the build status
[05:14:49.825] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:49.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:31:0) after 1m0s
[05:15:49.843] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:31:0)
[05:15:49.843] Timer tick, asking Buildbucket for the build status
[05:15:49.907] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:49.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:32:0) after 1m0s
[05:16:49.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:32:0)
[05:16:49.908] Timer tick, asking Buildbucket for the build status
[05:16:49.945] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:49.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:33:0) after 1m0s
[05:17:49.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:33:0)
[05:17:49.967] Timer tick, asking Buildbucket for the build status
[05:17:50.065] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:50.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:34:0) after 1m0s
[05:18:50.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:34:0)
[05:18:50.069] Timer tick, asking Buildbucket for the build status
[05:18:50.125] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:50.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:35:0) after 1m0s
[05:19:50.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:35:0)
[05:19:50.122] Timer tick, asking Buildbucket for the build status
[05:19:50.189] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:50.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:36:0) after 1m0s
[05:20:50.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:36:0)
[05:20:50.206] Timer tick, asking Buildbucket for the build status
[05:20:50.280] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:50.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:37:0) after 1m0s
[05:21:50.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:37:0)
[05:21:50.280] Timer tick, asking Buildbucket for the build status
[05:21:50.346] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:50.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:38:0) after 1m0s
[05:22:50.365] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:38:0)
[05:22:50.365] Timer tick, asking Buildbucket for the build status
[05:22:50.429] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:50.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:39:0) after 1m0s
[05:23:50.447] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:39:0)
[05:23:50.447] Timer tick, asking Buildbucket for the build status
[05:23:50.491] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:50.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:40:0) after 1m0s
[05:24:50.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:40:0)
[05:24:50.509] Timer tick, asking Buildbucket for the build status
[05:24:50.578] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:50.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:41:0) after 1m0s
[05:25:50.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:41:0)
[05:25:50.615] Timer tick, asking Buildbucket for the build status
[05:25:50.711] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:50.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:42:0) after 1m0s
[05:26:50.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:42:0)
[05:26:50.808] Timer tick, asking Buildbucket for the build status
[05:26:50.934] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:50.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:43:0) after 1m0s
[05:27:50.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:43:0)
[05:27:50.996] Timer tick, asking Buildbucket for the build status
[05:27:51.043] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:51.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:44:0) after 1m0s
[05:28:51.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:44:0)
[05:28:51.065] Timer tick, asking Buildbucket for the build status
[05:28:51.358] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:51.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:45:0) after 1m0s
[05:29:51.381] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:45:0)
[05:29:51.381] Timer tick, asking Buildbucket for the build status
[05:29:51.536] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:51.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:46:0) after 1m0s
[05:30:51.565] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:46:0)
[05:30:51.565] Timer tick, asking Buildbucket for the build status
[05:30:51.610] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:51.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:47:0) after 1m0s
[05:31:51.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:47:0)
[05:31:51.620] Timer tick, asking Buildbucket for the build status
[05:31:51.684] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:51.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:48:0) after 1m0s
[05:32:51.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:48:0)
[05:32:51.704] Timer tick, asking Buildbucket for the build status
[05:32:51.854] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:51.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:49:0) after 1m0s
[05:33:51.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:49:0)
[05:33:51.859] Timer tick, asking Buildbucket for the build status
[05:33:51.922] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:51.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:50:0) after 1m0s
[05:34:51.941] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:50:0)
[05:34:51.941] Timer tick, asking Buildbucket for the build status
[05:34:52.059] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:52.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:51:0) after 1m0s
[05:35:52.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:51:0)
[05:35:52.079] Timer tick, asking Buildbucket for the build status
[05:35:52.120] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:52.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:52:0) after 1m0s
[05:36:52.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:52:0)
[05:36:52.128] Timer tick, asking Buildbucket for the build status
[05:36:52.181] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:52.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:53:0) after 1m0s
[05:37:52.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:53:0)
[05:37:52.200] Timer tick, asking Buildbucket for the build status
[05:37:52.317] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:52.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:54:0) after 1m0s
[05:38:52.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:54:0)
[05:38:52.299] Timer tick, asking Buildbucket for the build status
[05:38:52.337] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:52.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:55:0) after 1m0s
[05:39:52.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:55:0)
[05:39:52.355] Timer tick, asking Buildbucket for the build status
[05:39:52.418] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:52.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:56:0) after 1m0s
[05:40:52.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:56:0)
[05:40:52.417] Timer tick, asking Buildbucket for the build status
[05:40:52.518] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:52.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:57:0) after 1m0s
[05:41:52.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:57:0)
[05:41:52.537] Timer tick, asking Buildbucket for the build status
[05:41:52.658] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:52.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:58:0) after 1m0s
[05:42:52.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:58:0)
[05:42:52.656] Timer tick, asking Buildbucket for the build status
[05:42:52.699] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:52.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:59:0) after 1m0s
[05:43:52.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:59:0)
[05:43:52.718] Timer tick, asking Buildbucket for the build status
[05:43:52.815] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:52.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:60:0) after 1m0s
[05:44:52.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:60:0)
[05:44:52.837] Timer tick, asking Buildbucket for the build status
[05:44:52.899] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:52.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:61:0) after 1m0s
[05:45:52.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:61:0)
[05:45:52.922] Timer tick, asking Buildbucket for the build status
[05:45:52.996] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:52.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:62:0) after 1m0s
[05:46:53.018] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:62:0)
[05:46:53.018] Timer tick, asking Buildbucket for the build status
[05:46:53.069] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:53.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:63:0) after 1m0s
[05:47:53.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:63:0)
[05:47:53.067] Timer tick, asking Buildbucket for the build status
[05:47:53.366] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:53.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:64:0) after 1m0s
[05:48:53.356] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:64:0)
[05:48:53.356] Timer tick, asking Buildbucket for the build status
[05:48:53.431] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:53.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:65:0) after 1m0s
[05:49:53.447] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:65:0)
[05:49:53.447] Timer tick, asking Buildbucket for the build status
[05:49:53.525] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:53.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:66:0) after 1m0s
[05:50:53.541] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:66:0)
[05:50:53.541] Timer tick, asking Buildbucket for the build status
[05:50:53.651] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:53.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:67:0) after 1m0s
[05:51:53.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:67:0)
[05:51:53.673] Timer tick, asking Buildbucket for the build status
[05:51:53.716] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:53.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:68:0) after 1m0s
[05:52:53.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:68:0)
[05:52:53.737] Timer tick, asking Buildbucket for the build status
[05:52:53.781] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:53.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:69:0) after 1m0s
[05:53:53.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:69:0)
[05:53:53.803] Timer tick, asking Buildbucket for the build status
[05:53:53.862] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:53.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:70:0) after 1m0s
[05:54:53.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:70:0)
[05:54:53.882] Timer tick, asking Buildbucket for the build status
[05:54:53.923] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:53.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:71:0) after 1m0s
[05:55:53.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:71:0)
[05:55:53.943] Timer tick, asking Buildbucket for the build status
[05:55:54.019] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:54.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:72:0) after 1m0s
[05:56:54.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:72:0)
[05:56:54.047] Timer tick, asking Buildbucket for the build status
[05:56:54.121] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:54.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:73:0) after 1m0s
[05:57:54.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:73:0)
[05:57:54.114] Timer tick, asking Buildbucket for the build status
[05:57:54.274] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:54.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:74:0) after 1m0s
[05:58:54.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:74:0)
[05:58:54.340] Timer tick, asking Buildbucket for the build status
[05:58:54.409] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:54.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:75:0) after 1m0s
[05:59:54.418] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:75:0)
[05:59:54.418] Timer tick, asking Buildbucket for the build status
[05:59:54.696] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:54.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:76:0) after 1m0s
[06:00:54.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:76:0)
[06:00:54.719] Timer tick, asking Buildbucket for the build status
[06:00:54.785] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:54.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:77:0) after 1m0s
[06:01:54.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:77:0)
[06:01:54.808] Timer tick, asking Buildbucket for the build status
[06:01:54.848] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:54.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:78:0) after 1m0s
[06:02:54.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:78:0)
[06:02:54.838] Timer tick, asking Buildbucket for the build status
[06:02:54.892] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:54.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:79:0) after 1m0s
[06:03:54.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:79:0)
[06:03:54.892] Timer tick, asking Buildbucket for the build status
[06:03:55.256] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:55.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:80:0) after 1m0s
[06:04:55.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:80:0)
[06:04:55.278] Timer tick, asking Buildbucket for the build status
[06:04:55.331] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:55.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:81:0) after 1m0s
[06:05:55.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:81:0)
[06:05:55.362] Timer tick, asking Buildbucket for the build status
[06:05:55.408] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:55.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:82:0) after 1m0s
[06:06:55.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:82:0)
[06:06:55.429] Timer tick, asking Buildbucket for the build status
[06:06:55.558] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:55.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:83:0) after 1m0s
[06:07:55.554] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:83:0)
[06:07:55.554] Timer tick, asking Buildbucket for the build status
[06:07:55.662] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:55.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:84:0) after 1m0s
[06:08:55.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:84:0)
[06:08:55.683] Timer tick, asking Buildbucket for the build status
[06:08:55.968] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:55.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:85:0) after 1m0s
[06:09:55.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:85:0)
[06:09:55.988] Timer tick, asking Buildbucket for the build status
[06:09:56.168] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:56.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:86:0) after 1m0s
[06:10:56.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:86:0)
[06:10:56.189] Timer tick, asking Buildbucket for the build status
[06:10:56.247] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:56.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:87:0) after 1m0s
[06:11:56.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:87:0)
[06:11:56.257] Timer tick, asking Buildbucket for the build status
[06:11:57.219] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:57.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:88:0) after 1m0s
[06:12:57.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:88:0)
[06:12:57.259] Timer tick, asking Buildbucket for the build status
[06:12:57.398] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:57.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:89:0) after 1m0s
[06:13:57.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:89:0)
[06:13:57.392] Timer tick, asking Buildbucket for the build status
[06:13:57.509] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:57.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:90:0) after 1m0s
[06:14:57.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:90:0)
[06:14:57.531] Timer tick, asking Buildbucket for the build status
[06:14:57.873] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:57.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:91:0) after 1m0s
[06:15:57.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:91:0)
[06:15:57.899] Timer tick, asking Buildbucket for the build status
[06:15:57.948] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:57.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:92:0) after 1m0s
[06:16:57.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:92:0)
[06:16:57.956] Timer tick, asking Buildbucket for the build status
[06:16:58.025] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:58.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:93:0) after 1m0s
[06:17:58.022] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:93:0)
[06:17:58.022] Timer tick, asking Buildbucket for the build status
[06:17:58.161] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:58.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:94:0) after 1m0s
[06:18:58.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:94:0)
[06:18:58.180] Timer tick, asking Buildbucket for the build status
[06:18:58.302] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:58.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:95:0) after 1m0s
[06:19:58.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:95:0)
[06:19:58.325] Timer tick, asking Buildbucket for the build status
[06:19:58.392] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:58.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:96:0) after 1m0s
[06:20:58.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:96:0)
[06:20:58.410] Timer tick, asking Buildbucket for the build status
[06:20:58.472] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:58.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:97:0) after 1m0s
[06:21:58.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:97:0)
[06:21:58.487] Timer tick, asking Buildbucket for the build status
[06:21:58.607] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:58.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:98:0) after 1m0s
[06:22:58.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:98:0)
[06:22:58.605] Timer tick, asking Buildbucket for the build status
[06:22:58.687] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:58.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:99:0) after 1m0s
[06:23:58.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:99:0)
[06:23:58.709] Timer tick, asking Buildbucket for the build status
[06:23:58.796] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:58.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:100:0) after 1m0s
[06:24:58.817] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:100:0)
[06:24:58.817] Timer tick, asking Buildbucket for the build status
[06:24:58.886] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:58.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:101:0) after 1m0s
[06:25:58.958] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:101:0)
[06:25:58.958] Timer tick, asking Buildbucket for the build status
[06:25:59.028] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:59.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:102:0) after 1m0s
[06:26:59.049] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:102:0)
[06:26:59.049] Timer tick, asking Buildbucket for the build status
[06:26:59.106] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:59.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:103:0) after 1m0s
[06:27:59.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:103:0)
[06:27:59.128] Timer tick, asking Buildbucket for the build status
[06:27:59.170] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:59.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:104:0) after 1m0s
[06:28:59.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:104:0)
[06:28:59.189] Timer tick, asking Buildbucket for the build status
[06:28:59.265] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:59.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:105:0) after 1m0s
[06:29:59.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:105:0)
[06:29:59.301] Timer tick, asking Buildbucket for the build status
[06:29:59.364] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:59.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:106:0) after 1m0s
[06:30:59.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:106:0)
[06:30:59.391] Timer tick, asking Buildbucket for the build status
[06:30:59.453] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:59.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:107:0) after 1m0s
[06:31:59.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:107:0)
[06:31:59.436] Timer tick, asking Buildbucket for the build status
[06:31:59.506] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:59.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:108:0) after 1m0s
[06:32:59.527] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:108:0)
[06:32:59.527] Timer tick, asking Buildbucket for the build status
[06:32:59.571] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:59.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:109:0) after 1m0s
[06:33:59.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:109:0)
[06:33:59.548] Timer tick, asking Buildbucket for the build status
[06:33:59.611] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:59.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:110:0) after 1m0s
[06:34:59.636] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:110:0)
[06:34:59.636] Timer tick, asking Buildbucket for the build status
[06:34:59.881] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:59.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:111:0) after 1m0s
[06:35:59.901] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:111:0)
[06:35:59.901] Timer tick, asking Buildbucket for the build status
[06:36:00.090] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:00.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:112:0) after 1m0s
[06:37:00.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:112:0)
[06:37:00.115] Timer tick, asking Buildbucket for the build status
[06:37:00.248] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:00.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:113:0) after 1m0s
[06:38:00.269] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:113:0)
[06:38:00.269] Timer tick, asking Buildbucket for the build status
[06:38:00.337] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:00.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:114:0) after 1m0s
[06:39:00.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:114:0)
[06:39:00.358] Timer tick, asking Buildbucket for the build status
[06:39:00.410] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:00.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:115:0) after 1m0s
[06:40:00.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:115:0)
[06:40:00.430] Timer tick, asking Buildbucket for the build status
[06:40:00.499] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:00.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:116:0) after 1m0s
[06:41:00.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:116:0)
[06:41:00.529] Timer tick, asking Buildbucket for the build status
[06:41:00.649] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:00.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:117:0) after 1m0s
[06:42:00.629] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:117:0)
[06:42:00.629] Timer tick, asking Buildbucket for the build status
[06:42:00.894] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:00.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:118:0) after 1m0s
[06:43:00.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:118:0)
[06:43:00.921] Timer tick, asking Buildbucket for the build status
[06:43:00.962] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:00.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:119:0) after 1m0s
[06:44:00.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:119:0)
[06:44:00.989] Timer tick, asking Buildbucket for the build status
[06:44:01.303] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:01.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:120:0) after 1m0s
[06:45:01.333] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:120:0)
[06:45:01.333] Timer tick, asking Buildbucket for the build status
[06:45:01.411] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:01.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:121:0) after 1m0s
[06:46:01.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:121:0)
[06:46:01.442] Timer tick, asking Buildbucket for the build status
[06:46:01.502] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:01.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:122:0) after 1m0s
[06:47:01.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:122:0)
[06:47:01.531] Timer tick, asking Buildbucket for the build status
[06:47:01.588] Build 8917770427516691200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:01.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083200489553356224:123:0) after 1m0s
[06:47:27.930] Received PubSub notification, asking Buildbucket for the build status
[06:47:27.992] Build 8917770427516691200: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[06:47:27.992] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553755647590146",
"created_by": "project:fuchsia",
"created_ts": "1553748405878965",
"failure_reason": "BUILD_FAILURE",
"id": "8917770427516691200",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"24fa56bcc8b9e61f450cc5c12d8c6da6dabf782d\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-7887e815-us-central1-b-f9j0\", \"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\": \"24fa56bcc8b9e61f450cc5c12d8c6da6dabf782d\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-7887e815-us-central1-b-f9j0\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4224-5f5030f\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1553748419079800",
"status": "COMPLETED",
"status_changed_ts": "1553748419324534",
"tags": [
"builder:clang",
"buildset:commit/git/24fa56bcc8b9e61f450cc5c12d8c6da6dabf782d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/24fa56bcc8b9e61f450cc5c12d8c6da6dabf782d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083200489553356224",
"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/8917770427516691200/+/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:43db70bb10c0a410",
"user_agent:luci-scheduler"
],
"updated_ts": "1553755647646559",
"url": "https://ci.chromium.org/b/8917770427516691200",
"utcnow_ts": "1553755647981950"
}
}
[06:47:27.992] Invocation finished in 2h0m43.440173533s with status FAILED