[06:25:06.148] New invocation is queued and will start shortly
[06:25:07.266] Starting the invocation (attempt 1)
[06:25:07.300] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:25:07.300] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:25:07.300] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9082741316477908992",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"9d1449246dd76a2e8c94bbeae055318356ee301a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9082741316477908992",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/9d1449246dd76a2e8c94bbeae055318356ee301a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/9d1449246dd76a2e8c94bbeae055318356ee301a",
"gitiles_ref:refs/heads/master"
]
}
[06:25:07.765] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1554186307365153",
"id": "8917311254526174240",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"9d1449246dd76a2e8c94bbeae055318356ee301a\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554186307634578",
"tags": [
"builder:clang",
"buildset:commit/git/9d1449246dd76a2e8c94bbeae055318356ee301a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/9d1449246dd76a2e8c94bbeae055318356ee301a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082741316477908992",
"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/8917311254526174240/+/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": "1554186307634699",
"url": "https://ci.chromium.org/b/8917311254526174240",
"utcnow_ts": "1554186307756574"
}
}
[06:25:07.765] Task URL: https://ci.chromium.org/b/8917311254526174240
[06:25:07.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:2:0) after 1m0s
[06:25:34.558] Received PubSub notification, asking Buildbucket for the build status
[06:25:34.639] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:07.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:2:0)
[06:26:07.783] Timer tick, asking Buildbucket for the build status
[06:26:07.922] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:07.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:4:0) after 1m0s
[06:27:07.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:4:0)
[06:27:07.943] Timer tick, asking Buildbucket for the build status
[06:27:08.042] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:08.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:5:0) after 1m0s
[06:28:08.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:5:0)
[06:28:08.095] Timer tick, asking Buildbucket for the build status
[06:28:08.254] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:08.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:6:0) after 1m0s
[06:29:08.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:6:0)
[06:29:08.271] Timer tick, asking Buildbucket for the build status
[06:29:08.384] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:08.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:7:0) after 1m0s
[06:30:08.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:7:0)
[06:30:08.412] Timer tick, asking Buildbucket for the build status
[06:30:08.499] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:08.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:8:0) after 1m0s
[06:31:08.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:8:0)
[06:31:08.492] Timer tick, asking Buildbucket for the build status
[06:31:08.547] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:08.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:9:0) after 1m0s
[06:32:08.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:9:0)
[06:32:08.584] Timer tick, asking Buildbucket for the build status
[06:32:08.641] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:08.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:10:0) after 1m0s
[06:33:08.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:10:0)
[06:33:08.666] Timer tick, asking Buildbucket for the build status
[06:33:08.709] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:08.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:11:0) after 1m0s
[06:34:08.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:11:0)
[06:34:08.785] Timer tick, asking Buildbucket for the build status
[06:34:08.914] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:08.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:12:0) after 1m0s
[06:35:08.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:12:0)
[06:35:08.930] Timer tick, asking Buildbucket for the build status
[06:35:08.998] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:08.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:13:0) after 1m0s
[06:36:09.024] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:13:0)
[06:36:09.024] Timer tick, asking Buildbucket for the build status
[06:36:09.131] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:09.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:14:0) after 1m0s
[06:37:09.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:14:0)
[06:37:09.172] Timer tick, asking Buildbucket for the build status
[06:37:09.237] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:09.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:15:0) after 1m0s
[06:38:09.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:15:0)
[06:38:09.225] Timer tick, asking Buildbucket for the build status
[06:38:09.264] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:09.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:16:0) after 1m0s
[06:39:09.267] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:16:0)
[06:39:09.267] Timer tick, asking Buildbucket for the build status
[06:39:09.313] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:09.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:17:0) after 1m0s
[06:40:09.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:17:0)
[06:40:09.340] Timer tick, asking Buildbucket for the build status
[06:40:09.421] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:09.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:18:0) after 1m0s
[06:41:09.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:18:0)
[06:41:09.431] Timer tick, asking Buildbucket for the build status
[06:41:09.500] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:09.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:19:0) after 1m0s
[06:42:09.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:19:0)
[06:42:09.519] Timer tick, asking Buildbucket for the build status
[06:42:09.560] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:09.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:20:0) after 1m0s
[06:43:09.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:20:0)
[06:43:09.580] Timer tick, asking Buildbucket for the build status
[06:43:09.655] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:09.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:21:0) after 1m0s
[06:44:09.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:21:0)
[06:44:09.679] Timer tick, asking Buildbucket for the build status
[06:44:09.739] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:09.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:22:0) after 1m0s
[06:45:09.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:22:0)
[06:45:09.756] Timer tick, asking Buildbucket for the build status
[06:45:09.954] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:09.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:23:0) after 1m0s
[06:46:09.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:23:0)
[06:46:09.950] Timer tick, asking Buildbucket for the build status
[06:46:10.020] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:10.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:24:0) after 1m0s
[06:47:10.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:24:0)
[06:47:10.044] Timer tick, asking Buildbucket for the build status
[06:47:10.103] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:10.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:25:0) after 1m0s
[06:48:10.119] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:25:0)
[06:48:10.119] Timer tick, asking Buildbucket for the build status
[06:48:10.216] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:10.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:26:0) after 1m0s
[06:49:10.239] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:26:0)
[06:49:10.239] Timer tick, asking Buildbucket for the build status
[06:49:10.355] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:10.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:27:0) after 1m0s
[06:50:10.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:27:0)
[06:50:10.375] Timer tick, asking Buildbucket for the build status
[06:50:10.432] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:10.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:28:0) after 1m0s
[06:51:10.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:28:0)
[06:51:10.449] Timer tick, asking Buildbucket for the build status
[06:51:10.539] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:10.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:29:0) after 1m0s
[06:52:10.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:29:0)
[06:52:10.559] Timer tick, asking Buildbucket for the build status
[06:52:10.733] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:10.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:30:0) after 1m0s
[06:53:10.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:30:0)
[06:53:10.754] Timer tick, asking Buildbucket for the build status
[06:53:10.865] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:10.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:31:0) after 1m0s
[06:54:10.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:31:0)
[06:54:10.898] Timer tick, asking Buildbucket for the build status
[06:54:11.005] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:11.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:32:0) after 1m0s
[06:55:11.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:32:0)
[06:55:11.034] Timer tick, asking Buildbucket for the build status
[06:55:11.195] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:11.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:33:0) after 1m0s
[06:56:11.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:33:0)
[06:56:11.217] Timer tick, asking Buildbucket for the build status
[06:56:11.351] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:11.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:34:0) after 1m0s
[06:57:11.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:34:0)
[06:57:11.374] Timer tick, asking Buildbucket for the build status
[06:57:11.436] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:11.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:35:0) after 1m0s
[06:58:11.455] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:35:0)
[06:58:11.455] Timer tick, asking Buildbucket for the build status
[06:58:11.565] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:11.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:36:0) after 1m0s
[06:59:11.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:36:0)
[06:59:11.582] Timer tick, asking Buildbucket for the build status
[06:59:11.682] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:11.682] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:37:0) after 1m0s
[07:00:11.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:37:0)
[07:00:11.908] Timer tick, asking Buildbucket for the build status
[07:00:12.001] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:12.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:38:0) after 1m0s
[07:01:12.059] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:38:0)
[07:01:12.059] Timer tick, asking Buildbucket for the build status
[07:01:12.121] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:12.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:39:0) after 1m0s
[07:02:12.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:39:0)
[07:02:12.144] Timer tick, asking Buildbucket for the build status
[07:02:12.397] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:12.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:40:0) after 1m0s
[07:03:12.429] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:40:0)
[07:03:12.429] Timer tick, asking Buildbucket for the build status
[07:03:12.496] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:12.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:41:0) after 1m0s
[07:04:12.512] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:41:0)
[07:04:12.512] Timer tick, asking Buildbucket for the build status
[07:04:12.589] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:12.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:42:0) after 1m0s
[07:05:12.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:42:0)
[07:05:12.630] Timer tick, asking Buildbucket for the build status
[07:05:12.909] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:12.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:43:0) after 1m0s
[07:06:12.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:43:0)
[07:06:12.930] Timer tick, asking Buildbucket for the build status
[07:06:13.128] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:13.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:44:0) after 1m0s
[07:07:13.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:44:0)
[07:07:13.145] Timer tick, asking Buildbucket for the build status
[07:07:13.255] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:13.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:45:0) after 1m0s
[07:08:13.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:45:0)
[07:08:13.276] Timer tick, asking Buildbucket for the build status
[07:08:13.384] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:13.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:46:0) after 1m0s
[07:09:13.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:46:0)
[07:09:13.406] Timer tick, asking Buildbucket for the build status
[07:09:13.447] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:13.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:47:0) after 1m0s
[07:10:13.466] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:47:0)
[07:10:13.466] Timer tick, asking Buildbucket for the build status
[07:10:13.590] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:13.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:48:0) after 1m0s
[07:11:13.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:48:0)
[07:11:13.609] Timer tick, asking Buildbucket for the build status
[07:11:13.694] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:13.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:49:0) after 1m0s
[07:12:13.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:49:0)
[07:12:13.713] Timer tick, asking Buildbucket for the build status
[07:12:13.979] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:13.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:50:0) after 1m0s
[07:13:14.032] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:50:0)
[07:13:14.032] Timer tick, asking Buildbucket for the build status
[07:13:14.201] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:14.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:51:0) after 1m0s
[07:14:14.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:51:0)
[07:14:14.246] Timer tick, asking Buildbucket for the build status
[07:14:14.425] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:14.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:52:0) after 1m0s
[07:15:14.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:52:0)
[07:15:14.397] Timer tick, asking Buildbucket for the build status
[07:15:14.494] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:14.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:53:0) after 1m0s
[07:16:14.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:53:0)
[07:16:14.514] Timer tick, asking Buildbucket for the build status
[07:16:14.692] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:14.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:54:0) after 1m0s
[07:17:14.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:54:0)
[07:17:14.672] Timer tick, asking Buildbucket for the build status
[07:17:14.810] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:14.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:55:0) after 1m0s
[07:18:14.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:55:0)
[07:18:14.831] Timer tick, asking Buildbucket for the build status
[07:18:15.228] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:15.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:56:0) after 1m0s
[07:19:15.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:56:0)
[07:19:15.249] Timer tick, asking Buildbucket for the build status
[07:19:15.322] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:15.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:57:0) after 1m0s
[07:20:15.349] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:57:0)
[07:20:15.350] Timer tick, asking Buildbucket for the build status
[07:20:15.558] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:15.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:58:0) after 1m0s
[07:21:15.575] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:58:0)
[07:21:15.575] Timer tick, asking Buildbucket for the build status
[07:21:15.767] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:15.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:59:0) after 1m0s
[07:22:15.740] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:59:0)
[07:22:15.740] Timer tick, asking Buildbucket for the build status
[07:22:15.896] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:15.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:60:0) after 1m0s
[07:23:15.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:60:0)
[07:23:15.917] Timer tick, asking Buildbucket for the build status
[07:23:16.043] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:16.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:61:0) after 1m0s
[07:24:16.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:61:0)
[07:24:16.067] Timer tick, asking Buildbucket for the build status
[07:24:16.207] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:16.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:62:0) after 1m0s
[07:25:16.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:62:0)
[07:25:16.209] Timer tick, asking Buildbucket for the build status
[07:25:16.500] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:16.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:63:0) after 1m0s
[07:26:16.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:63:0)
[07:26:16.518] Timer tick, asking Buildbucket for the build status
[07:26:16.854] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:16.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:64:0) after 1m0s
[07:27:16.874] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:64:0)
[07:27:16.874] Timer tick, asking Buildbucket for the build status
[07:27:16.941] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:16.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:65:0) after 1m0s
[07:28:16.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:65:0)
[07:28:16.959] Timer tick, asking Buildbucket for the build status
[07:28:17.093] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:17.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:66:0) after 1m0s
[07:29:17.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:66:0)
[07:29:17.115] Timer tick, asking Buildbucket for the build status
[07:29:17.275] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:17.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:67:0) after 1m0s
[07:30:17.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:67:0)
[07:30:17.296] Timer tick, asking Buildbucket for the build status
[07:30:17.379] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:17.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:68:0) after 1m0s
[07:31:17.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:68:0)
[07:31:17.368] Timer tick, asking Buildbucket for the build status
[07:31:17.472] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:17.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:69:0) after 1m0s
[07:32:17.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:69:0)
[07:32:17.490] Timer tick, asking Buildbucket for the build status
[07:32:17.669] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:17.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:70:0) after 1m0s
[07:33:17.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:70:0)
[07:33:17.688] Timer tick, asking Buildbucket for the build status
[07:33:17.940] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:17.940] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:71:0) after 1m0s
[07:34:17.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:71:0)
[07:34:17.965] Timer tick, asking Buildbucket for the build status
[07:34:18.081] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:18.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:72:0) after 1m0s
[07:35:18.130] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:72:0)
[07:35:18.130] Timer tick, asking Buildbucket for the build status
[07:35:18.206] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:18.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:73:0) after 1m0s
[07:36:18.234] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:73:0)
[07:36:18.234] Timer tick, asking Buildbucket for the build status
[07:36:18.293] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:18.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:74:0) after 1m0s
[07:37:18.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:74:0)
[07:37:18.289] Timer tick, asking Buildbucket for the build status
[07:37:18.354] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:18.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:75:0) after 1m0s
[07:38:18.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:75:0)
[07:38:18.379] Timer tick, asking Buildbucket for the build status
[07:38:18.575] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:18.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:76:0) after 1m0s
[07:39:18.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:76:0)
[07:39:18.599] Timer tick, asking Buildbucket for the build status
[07:39:18.677] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:18.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:77:0) after 1m0s
[07:40:18.697] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:77:0)
[07:40:18.697] Timer tick, asking Buildbucket for the build status
[07:40:18.754] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:18.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:78:0) after 1m0s
[07:41:18.746] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:78:0)
[07:41:18.746] Timer tick, asking Buildbucket for the build status
[07:41:18.813] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:18.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:79:0) after 1m0s
[07:42:18.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:79:0)
[07:42:18.837] Timer tick, asking Buildbucket for the build status
[07:42:18.960] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:18.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:80:0) after 1m0s
[07:43:18.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:80:0)
[07:43:18.980] Timer tick, asking Buildbucket for the build status
[07:43:19.043] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:19.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:81:0) after 1m0s
[07:44:19.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:81:0)
[07:44:19.068] Timer tick, asking Buildbucket for the build status
[07:44:19.134] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:19.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:82:0) after 1m0s
[07:45:19.163] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:82:0)
[07:45:19.163] Timer tick, asking Buildbucket for the build status
[07:45:19.231] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:19.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:83:0) after 1m0s
[07:46:19.253] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:83:0)
[07:46:19.253] Timer tick, asking Buildbucket for the build status
[07:46:19.320] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:19.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:84:0) after 1m0s
[07:47:19.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:84:0)
[07:47:19.294] Timer tick, asking Buildbucket for the build status
[07:47:19.347] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:19.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:85:0) after 1m0s
[07:48:19.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:85:0)
[07:48:19.369] Timer tick, asking Buildbucket for the build status
[07:48:19.509] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:19.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:86:0) after 1m0s
[07:49:19.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:86:0)
[07:49:19.529] Timer tick, asking Buildbucket for the build status
[07:49:19.625] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:19.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:87:0) after 1m0s
[07:50:19.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:87:0)
[07:50:19.646] Timer tick, asking Buildbucket for the build status
[07:50:19.803] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:19.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:88:0) after 1m0s
[07:51:19.824] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:88:0)
[07:51:19.824] Timer tick, asking Buildbucket for the build status
[07:51:20.282] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:20.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:89:0) after 1m0s
[07:52:20.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:89:0)
[07:52:20.276] Timer tick, asking Buildbucket for the build status
[07:52:20.315] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:20.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:90:0) after 1m0s
[07:53:20.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:90:0)
[07:53:20.326] Timer tick, asking Buildbucket for the build status
[07:53:20.405] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:20.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:91:0) after 1m0s
[07:54:20.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:91:0)
[07:54:20.391] Timer tick, asking Buildbucket for the build status
[07:54:20.581] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:20.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:92:0) after 1m0s
[07:55:20.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:92:0)
[07:55:20.569] Timer tick, asking Buildbucket for the build status
[07:55:20.677] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:20.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:93:0) after 1m0s
[07:56:20.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:93:0)
[07:56:20.698] Timer tick, asking Buildbucket for the build status
[07:56:20.839] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:20.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:94:0) after 1m0s
[07:57:20.850] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:94:0)
[07:57:20.850] Timer tick, asking Buildbucket for the build status
[07:57:21.667] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:21.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:95:0) after 1m0s
[07:58:21.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:95:0)
[07:58:21.685] Timer tick, asking Buildbucket for the build status
[07:58:21.786] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:21.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:96:0) after 1m0s
[07:59:21.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:96:0)
[07:59:21.848] Timer tick, asking Buildbucket for the build status
[07:59:22.103] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:22.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:97:0) after 1m0s
[08:00:22.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:97:0)
[08:00:22.134] Timer tick, asking Buildbucket for the build status
[08:00:22.193] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:22.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:98:0) after 1m0s
[08:01:22.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:98:0)
[08:01:22.212] Timer tick, asking Buildbucket for the build status
[08:01:22.392] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:22.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:99:0) after 1m0s
[08:02:22.369] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:99:0)
[08:02:22.369] Timer tick, asking Buildbucket for the build status
[08:02:22.469] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:22.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:100:0) after 1m0s
[08:03:22.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:100:0)
[08:03:22.483] Timer tick, asking Buildbucket for the build status
[08:03:22.701] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:22.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:101:0) after 1m0s
[08:04:22.732] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:101:0)
[08:04:22.732] Timer tick, asking Buildbucket for the build status
[08:04:22.806] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:22.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:102:0) after 1m0s
[08:05:22.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:102:0)
[08:05:22.810] Timer tick, asking Buildbucket for the build status
[08:05:23.745] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:23.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:103:0) after 1m0s
[08:06:23.771] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:103:0)
[08:06:23.771] Timer tick, asking Buildbucket for the build status
[08:06:23.951] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:23.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:104:0) after 1m0s
[08:07:23.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:104:0)
[08:07:23.925] Timer tick, asking Buildbucket for the build status
[08:07:24.077] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:24.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:105:0) after 1m0s
[08:08:24.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:105:0)
[08:08:24.097] Timer tick, asking Buildbucket for the build status
[08:08:24.140] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:24.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:106:0) after 1m0s
[08:09:24.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:106:0)
[08:09:24.128] Timer tick, asking Buildbucket for the build status
[08:09:24.385] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:24.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:107:0) after 1m0s
[08:10:24.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:107:0)
[08:10:24.384] Timer tick, asking Buildbucket for the build status
[08:10:24.470] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:24.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:108:0) after 1m0s
[08:11:24.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:108:0)
[08:11:24.492] Timer tick, asking Buildbucket for the build status
[08:11:24.552] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:24.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:109:0) after 1m0s
[08:12:24.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:109:0)
[08:12:24.551] Timer tick, asking Buildbucket for the build status
[08:12:24.762] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:24.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:110:0) after 1m0s
[08:13:24.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:110:0)
[08:13:24.784] Timer tick, asking Buildbucket for the build status
[08:13:24.855] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:24.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:111:0) after 1m0s
[08:14:24.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:111:0)
[08:14:24.877] Timer tick, asking Buildbucket for the build status
[08:14:25.048] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:25.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:112:0) after 1m0s
[08:15:25.070] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:112:0)
[08:15:25.070] Timer tick, asking Buildbucket for the build status
[08:15:25.165] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:25.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:113:0) after 1m0s
[08:16:25.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:113:0)
[08:16:25.189] Timer tick, asking Buildbucket for the build status
[08:16:25.260] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:25.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:114:0) after 1m0s
[08:17:25.331] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:114:0)
[08:17:25.331] Timer tick, asking Buildbucket for the build status
[08:17:25.388] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:25.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:115:0) after 1m0s
[08:18:25.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:115:0)
[08:18:25.395] Timer tick, asking Buildbucket for the build status
[08:18:25.451] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:25.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:116:0) after 1m0s
[08:19:25.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:116:0)
[08:19:25.463] Timer tick, asking Buildbucket for the build status
[08:19:25.510] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:25.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:117:0) after 1m0s
[08:20:25.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:117:0)
[08:20:25.519] Timer tick, asking Buildbucket for the build status
[08:20:25.602] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:25.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:118:0) after 1m0s
[08:21:25.626] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:118:0)
[08:21:25.626] Timer tick, asking Buildbucket for the build status
[08:21:25.668] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:25.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:119:0) after 1m0s
[08:22:25.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:119:0)
[08:22:25.709] Timer tick, asking Buildbucket for the build status
[08:22:25.888] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:25.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:120:0) after 1m0s
[08:23:25.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:120:0)
[08:23:25.918] Timer tick, asking Buildbucket for the build status
[08:23:26.062] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:26.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:121:0) after 1m0s
[08:24:26.081] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:121:0)
[08:24:26.081] Timer tick, asking Buildbucket for the build status
[08:24:26.293] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:26.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:122:0) after 1m0s
[08:25:26.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:122:0)
[08:25:26.323] Timer tick, asking Buildbucket for the build status
[08:25:26.456] Build 8917311254526174240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:26.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082741316477908992:123:0) after 1m0s
[08:25:56.625] Received PubSub notification, asking Buildbucket for the build status
[08:25:56.669] Build 8917311254526174240: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:25:56.669] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1554193556374179",
"created_by": "project:fuchsia",
"created_ts": "1554186307365153",
"failure_reason": "BUILD_FAILURE",
"id": "8917311254526174240",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"9d1449246dd76a2e8c94bbeae055318356ee301a\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-3kc3\", \"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\": \"9d1449246dd76a2e8c94bbeae055318356ee301a\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-3kc3\"], \"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\": [\"4231-d78bb73\"], \"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": "1554186334066165",
"status": "COMPLETED",
"status_changed_ts": "1554186334375765",
"tags": [
"builder:clang",
"buildset:commit/git/9d1449246dd76a2e8c94bbeae055318356ee301a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/9d1449246dd76a2e8c94bbeae055318356ee301a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082741316477908992",
"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/8917311254526174240/+/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:43f58a918854e210",
"user_agent:luci-scheduler"
],
"updated_ts": "1554193556419725",
"url": "https://ci.chromium.org/b/8917311254526174240",
"utcnow_ts": "1554193556660825"
}
}
[08:25:56.669] Invocation finished in 2h0m50.548034822s with status FAILED