[13:03:20.191] New invocation is queued and will start shortly
[13:03:21.274] Starting the invocation (attempt 1)
[13:03:21.334] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:03:21.334] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:03:21.334] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080813725455335248",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"652168a99b5cdc1fb420b1224c8c2d42ad2b7465\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080813725455335248",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/652168a99b5cdc1fb420b1224c8c2d42ad2b7465",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/652168a99b5cdc1fb420b1224c8c2d42ad2b7465",
"gitiles_ref:refs/heads/master"
]
}
[13:03:22.234] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556024601490567",
"id": "8915383663426491456",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"652168a99b5cdc1fb420b1224c8c2d42ad2b7465\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556024601997624",
"tags": [
"builder:clang",
"buildset:commit/git/652168a99b5cdc1fb420b1224c8c2d42ad2b7465",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/652168a99b5cdc1fb420b1224c8c2d42ad2b7465",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080813725455335248",
"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/8915383663426491456/+/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": "1556024601997752",
"url": "https://ci.chromium.org/b/8915383663426491456",
"utcnow_ts": "1556024602221987"
}
}
[13:03:22.234] Task URL: https://ci.chromium.org/b/8915383663426491456
[13:03:22.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:2:0) after 1m0s
[13:03:56.244] Received PubSub notification, asking Buildbucket for the build status
[13:03:56.321] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:22.253] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:2:0)
[13:04:22.253] Timer tick, asking Buildbucket for the build status
[13:04:22.391] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:22.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:4:0) after 1m0s
[13:05:22.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:4:0)
[13:05:22.380] Timer tick, asking Buildbucket for the build status
[13:05:22.487] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:22.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:5:0) after 1m0s
[13:06:22.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:5:0)
[13:06:22.503] Timer tick, asking Buildbucket for the build status
[13:06:22.773] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:22.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:6:0) after 1m0s
[13:07:22.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:6:0)
[13:07:22.795] Timer tick, asking Buildbucket for the build status
[13:07:22.987] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:22.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:7:0) after 1m0s
[13:08:23.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:7:0)
[13:08:23.005] Timer tick, asking Buildbucket for the build status
[13:08:23.051] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:23.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:8:0) after 1m0s
[13:09:23.052] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:8:0)
[13:09:23.052] Timer tick, asking Buildbucket for the build status
[13:09:23.359] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:23.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:9:0) after 1m0s
[13:10:23.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:9:0)
[13:10:23.430] Timer tick, asking Buildbucket for the build status
[13:10:23.502] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:23.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:10:0) after 1m0s
[13:11:23.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:10:0)
[13:11:23.488] Timer tick, asking Buildbucket for the build status
[13:11:23.782] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:23.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:11:0) after 1m0s
[13:12:23.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:11:0)
[13:12:23.800] Timer tick, asking Buildbucket for the build status
[13:12:23.920] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:23.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:12:0) after 1m0s
[13:13:23.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:12:0)
[13:13:23.938] Timer tick, asking Buildbucket for the build status
[13:13:24.065] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:24.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:13:0) after 1m0s
[13:14:24.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:13:0)
[13:14:24.144] Timer tick, asking Buildbucket for the build status
[13:14:24.181] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:24.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:14:0) after 1m0s
[13:15:24.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:14:0)
[13:15:24.201] Timer tick, asking Buildbucket for the build status
[13:15:24.345] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:24.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:15:0) after 1m0s
[13:16:24.366] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:15:0)
[13:16:24.366] Timer tick, asking Buildbucket for the build status
[13:16:24.399] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:24.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:16:0) after 1m0s
[13:17:24.422] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:16:0)
[13:17:24.422] Timer tick, asking Buildbucket for the build status
[13:17:24.491] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:24.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:17:0) after 1m0s
[13:18:24.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:17:0)
[13:18:24.556] Timer tick, asking Buildbucket for the build status
[13:18:24.798] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:24.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:18:0) after 1m0s
[13:19:24.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:18:0)
[13:19:24.805] Timer tick, asking Buildbucket for the build status
[13:19:24.868] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:24.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:19:0) after 1m0s
[13:20:24.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:19:0)
[13:20:24.862] Timer tick, asking Buildbucket for the build status
[13:20:24.945] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:24.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:20:0) after 1m0s
[13:21:24.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:20:0)
[13:21:24.923] Timer tick, asking Buildbucket for the build status
[13:21:24.981] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:24.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:21:0) after 1m0s
[13:22:24.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:21:0)
[13:22:24.989] Timer tick, asking Buildbucket for the build status
[13:22:25.054] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:25.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:22:0) after 1m0s
[13:23:25.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:22:0)
[13:23:25.084] Timer tick, asking Buildbucket for the build status
[13:23:25.167] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:25.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:23:0) after 1m0s
[13:24:25.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:23:0)
[13:24:25.189] Timer tick, asking Buildbucket for the build status
[13:24:25.276] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:25.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:24:0) after 1m0s
[13:25:25.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:24:0)
[13:25:25.297] Timer tick, asking Buildbucket for the build status
[13:25:25.333] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:25.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:25:0) after 1m0s
[13:26:25.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:25:0)
[13:26:25.342] Timer tick, asking Buildbucket for the build status
[13:26:25.679] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:25.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:26:0) after 1m0s
[13:27:25.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:26:0)
[13:27:25.696] Timer tick, asking Buildbucket for the build status
[13:27:25.873] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:25.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:27:0) after 1m0s
[13:28:25.851] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:27:0)
[13:28:25.851] Timer tick, asking Buildbucket for the build status
[13:28:25.898] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:25.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:28:0) after 1m0s
[13:29:25.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:28:0)
[13:29:25.930] Timer tick, asking Buildbucket for the build status
[13:29:26.156] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:26.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:29:0) after 1m0s
[13:30:26.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:29:0)
[13:30:26.174] Timer tick, asking Buildbucket for the build status
[13:30:26.220] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:26.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:30:0) after 1m0s
[13:31:26.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:30:0)
[13:31:26.243] Timer tick, asking Buildbucket for the build status
[13:31:26.412] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:26.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:31:0) after 1m0s
[13:32:26.435] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:31:0)
[13:32:26.435] Timer tick, asking Buildbucket for the build status
[13:32:26.501] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:26.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:32:0) after 1m0s
[13:33:26.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:32:0)
[13:33:26.511] Timer tick, asking Buildbucket for the build status
[13:33:26.553] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:26.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:33:0) after 1m0s
[13:34:26.574] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:33:0)
[13:34:26.574] Timer tick, asking Buildbucket for the build status
[13:34:26.630] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:26.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:34:0) after 1m0s
[13:35:26.654] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:34:0)
[13:35:26.654] Timer tick, asking Buildbucket for the build status
[13:35:26.707] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:26.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:35:0) after 1m0s
[13:36:26.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:35:0)
[13:36:26.779] Timer tick, asking Buildbucket for the build status
[13:36:26.840] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:26.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:36:0) after 1m0s
[13:37:26.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:36:0)
[13:37:26.878] Timer tick, asking Buildbucket for the build status
[13:37:26.914] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:26.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:37:0) after 1m0s
[13:38:26.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:37:0)
[13:38:26.942] Timer tick, asking Buildbucket for the build status
[13:38:27.471] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:27.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:38:0) after 1m0s
[13:39:27.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:38:0)
[13:39:27.486] Timer tick, asking Buildbucket for the build status
[13:39:27.590] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:27.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:39:0) after 1m0s
[13:40:27.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:39:0)
[13:40:27.609] Timer tick, asking Buildbucket for the build status
[13:40:27.681] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:27.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:40:0) after 1m0s
[13:41:27.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:40:0)
[13:41:27.706] Timer tick, asking Buildbucket for the build status
[13:41:27.953] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:27.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:41:0) after 1m0s
[13:42:27.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:41:0)
[13:42:27.979] Timer tick, asking Buildbucket for the build status
[13:42:28.092] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:28.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:42:0) after 1m0s
[13:43:28.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:42:0)
[13:43:28.123] Timer tick, asking Buildbucket for the build status
[13:43:28.201] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:28.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:43:0) after 1m0s
[13:44:28.227] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:43:0)
[13:44:28.227] Timer tick, asking Buildbucket for the build status
[13:44:28.332] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:28.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:44:0) after 1m0s
[13:45:28.353] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:44:0)
[13:45:28.353] Timer tick, asking Buildbucket for the build status
[13:45:28.426] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:28.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:45:0) after 1m0s
[13:46:28.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:45:0)
[13:46:28.410] Timer tick, asking Buildbucket for the build status
[13:46:28.474] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:28.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:46:0) after 1m0s
[13:47:28.456] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:46:0)
[13:47:28.456] Timer tick, asking Buildbucket for the build status
[13:47:28.506] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:28.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:47:0) after 1m0s
[13:48:28.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:47:0)
[13:48:28.506] Timer tick, asking Buildbucket for the build status
[13:48:28.550] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:28.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:48:0) after 1m0s
[13:49:28.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:48:0)
[13:49:28.583] Timer tick, asking Buildbucket for the build status
[13:49:28.670] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:28.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:49:0) after 1m0s
[13:50:28.691] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:49:0)
[13:50:28.691] Timer tick, asking Buildbucket for the build status
[13:50:28.772] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:28.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:50:0) after 1m0s
[13:51:28.793] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:50:0)
[13:51:28.793] Timer tick, asking Buildbucket for the build status
[13:51:28.892] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:28.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:51:0) after 1m0s
[13:52:28.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:51:0)
[13:52:28.906] Timer tick, asking Buildbucket for the build status
[13:52:28.968] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:28.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:52:0) after 1m0s
[13:53:29.020] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:52:0)
[13:53:29.020] Timer tick, asking Buildbucket for the build status
[13:53:29.106] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:29.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:53:0) after 1m0s
[13:54:29.187] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:53:0)
[13:54:29.187] Timer tick, asking Buildbucket for the build status
[13:54:29.241] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:29.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:54:0) after 1m0s
[13:55:29.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:54:0)
[13:55:29.280] Timer tick, asking Buildbucket for the build status
[13:55:29.340] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:29.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:55:0) after 1m0s
[13:56:29.360] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:55:0)
[13:56:29.360] Timer tick, asking Buildbucket for the build status
[13:56:29.473] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:29.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:56:0) after 1m0s
[13:57:29.473] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:56:0)
[13:57:29.473] Timer tick, asking Buildbucket for the build status
[13:57:29.873] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:29.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:57:0) after 1m0s
[13:58:29.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:57:0)
[13:58:29.892] Timer tick, asking Buildbucket for the build status
[13:58:30.003] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:30.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:58:0) after 1m0s
[13:59:30.003] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:58:0)
[13:59:30.003] Timer tick, asking Buildbucket for the build status
[13:59:30.295] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:30.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:59:0) after 1m0s
[14:00:30.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:59:0)
[14:00:30.280] Timer tick, asking Buildbucket for the build status
[14:00:30.325] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:30.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:60:0) after 1m0s
[14:01:30.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:60:0)
[14:01:30.347] Timer tick, asking Buildbucket for the build status
[14:01:30.419] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:30.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:61:0) after 1m0s
[14:02:30.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:61:0)
[14:02:30.448] Timer tick, asking Buildbucket for the build status
[14:02:30.520] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:30.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:62:0) after 1m0s
[14:03:30.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:62:0)
[14:03:30.581] Timer tick, asking Buildbucket for the build status
[14:03:30.645] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:30.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:63:0) after 1m0s
[14:04:30.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:63:0)
[14:04:30.672] Timer tick, asking Buildbucket for the build status
[14:04:30.714] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:30.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:64:0) after 1m0s
[14:05:30.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:64:0)
[14:05:30.734] Timer tick, asking Buildbucket for the build status
[14:05:30.806] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:30.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:65:0) after 1m0s
[14:06:30.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:65:0)
[14:06:30.813] Timer tick, asking Buildbucket for the build status
[14:06:30.900] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:30.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:66:0) after 1m0s
[14:07:30.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:66:0)
[14:07:30.878] Timer tick, asking Buildbucket for the build status
[14:07:31.005] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:31.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:67:0) after 1m0s
[14:08:31.043] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:67:0)
[14:08:31.043] Timer tick, asking Buildbucket for the build status
[14:08:31.088] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:31.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:68:0) after 1m0s
[14:09:31.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:68:0)
[14:09:31.063] Timer tick, asking Buildbucket for the build status
[14:09:31.173] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:31.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:69:0) after 1m0s
[14:10:31.205] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:69:0)
[14:10:31.205] Timer tick, asking Buildbucket for the build status
[14:10:31.244] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:31.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:70:0) after 1m0s
[14:11:31.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:70:0)
[14:11:31.271] Timer tick, asking Buildbucket for the build status
[14:11:31.353] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:31.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:71:0) after 1m0s
[14:12:31.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:71:0)
[14:12:31.388] Timer tick, asking Buildbucket for the build status
[14:12:31.510] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:31.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:72:0) after 1m0s
[14:13:31.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:72:0)
[14:13:31.530] Timer tick, asking Buildbucket for the build status
[14:13:31.589] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:31.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:73:0) after 1m0s
[14:14:31.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:73:0)
[14:14:31.567] Timer tick, asking Buildbucket for the build status
[14:14:31.623] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:31.623] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:74:0) after 1m0s
[14:15:31.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:74:0)
[14:15:31.612] Timer tick, asking Buildbucket for the build status
[14:15:31.772] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:31.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:75:0) after 1m0s
[14:16:31.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:75:0)
[14:16:31.823] Timer tick, asking Buildbucket for the build status
[14:16:31.913] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:31.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:76:0) after 1m0s
[14:17:31.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:76:0)
[14:17:31.938] Timer tick, asking Buildbucket for the build status
[14:17:32.041] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:32.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:77:0) after 1m0s
[14:18:32.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:77:0)
[14:18:32.061] Timer tick, asking Buildbucket for the build status
[14:18:32.168] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:32.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:78:0) after 1m0s
[14:19:32.195] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:78:0)
[14:19:32.195] Timer tick, asking Buildbucket for the build status
[14:19:32.268] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:32.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:79:0) after 1m0s
[14:20:32.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:79:0)
[14:20:32.274] Timer tick, asking Buildbucket for the build status
[14:20:32.313] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:32.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:80:0) after 1m0s
[14:21:32.344] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:80:0)
[14:21:32.344] Timer tick, asking Buildbucket for the build status
[14:21:32.416] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:32.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:81:0) after 1m0s
[14:22:32.437] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:81:0)
[14:22:32.437] Timer tick, asking Buildbucket for the build status
[14:22:32.518] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:32.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:82:0) after 1m0s
[14:23:32.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:82:0)
[14:23:32.543] Timer tick, asking Buildbucket for the build status
[14:23:32.628] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:32.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:83:0) after 1m0s
[14:24:32.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:83:0)
[14:24:32.688] Timer tick, asking Buildbucket for the build status
[14:24:32.753] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:32.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:84:0) after 1m0s
[14:25:32.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:84:0)
[14:25:32.770] Timer tick, asking Buildbucket for the build status
[14:25:32.909] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:32.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:85:0) after 1m0s
[14:26:32.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:85:0)
[14:26:32.998] Timer tick, asking Buildbucket for the build status
[14:26:33.058] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:33.058] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:86:0) after 1m0s
[14:27:33.155] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:86:0)
[14:27:33.155] Timer tick, asking Buildbucket for the build status
[14:27:33.557] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:33.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:87:0) after 1m0s
[14:28:33.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:87:0)
[14:28:33.556] Timer tick, asking Buildbucket for the build status
[14:28:33.626] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:33.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:88:0) after 1m0s
[14:29:33.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:88:0)
[14:29:33.640] Timer tick, asking Buildbucket for the build status
[14:29:33.690] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:33.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:89:0) after 1m0s
[14:30:33.722] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:89:0)
[14:30:33.722] Timer tick, asking Buildbucket for the build status
[14:30:33.880] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:33.880] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:90:0) after 1m0s
[14:31:33.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:90:0)
[14:31:33.909] Timer tick, asking Buildbucket for the build status
[14:31:37.793] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:37.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:91:0) after 1m0s
[14:32:37.818] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:91:0)
[14:32:37.818] Timer tick, asking Buildbucket for the build status
[14:32:38.157] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:38.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:92:0) after 1m0s
[14:33:38.255] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:92:0)
[14:33:38.255] Timer tick, asking Buildbucket for the build status
[14:33:38.426] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:38.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:93:0) after 1m0s
[14:34:38.435] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:93:0)
[14:34:38.435] Timer tick, asking Buildbucket for the build status
[14:34:38.499] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:38.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:94:0) after 1m0s
[14:35:38.495] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:94:0)
[14:35:38.495] Timer tick, asking Buildbucket for the build status
[14:35:38.777] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:38.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:95:0) after 1m0s
[14:36:38.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:95:0)
[14:36:38.798] Timer tick, asking Buildbucket for the build status
[14:36:38.887] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:38.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:96:0) after 1m0s
[14:37:38.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:96:0)
[14:37:38.911] Timer tick, asking Buildbucket for the build status
[14:37:38.968] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:38.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:97:0) after 1m0s
[14:38:38.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:97:0)
[14:38:38.969] Timer tick, asking Buildbucket for the build status
[14:38:39.025] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:39.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:98:0) after 1m0s
[14:39:39.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:98:0)
[14:39:39.047] Timer tick, asking Buildbucket for the build status
[14:39:39.220] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:39.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:99:0) after 1m0s
[14:40:39.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:99:0)
[14:40:39.246] Timer tick, asking Buildbucket for the build status
[14:40:39.357] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:39.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:100:0) after 1m0s
[14:41:39.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:100:0)
[14:41:39.336] Timer tick, asking Buildbucket for the build status
[14:41:39.834] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:39.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:101:0) after 1m0s
[14:42:39.856] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:101:0)
[14:42:39.856] Timer tick, asking Buildbucket for the build status
[14:42:39.894] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:39.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:102:0) after 1m0s
[14:43:39.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:102:0)
[14:43:39.913] Timer tick, asking Buildbucket for the build status
[14:43:39.968] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:39.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:103:0) after 1m0s
[14:44:39.960] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:103:0)
[14:44:39.960] Timer tick, asking Buildbucket for the build status
[14:44:40.020] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:40.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:104:0) after 1m0s
[14:45:40.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:104:0)
[14:45:40.072] Timer tick, asking Buildbucket for the build status
[14:45:40.135] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:40.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:105:0) after 1m0s
[14:46:40.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:105:0)
[14:46:40.139] Timer tick, asking Buildbucket for the build status
[14:46:40.194] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:40.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:106:0) after 1m0s
[14:47:40.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:106:0)
[14:47:40.215] Timer tick, asking Buildbucket for the build status
[14:47:40.280] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:40.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:107:0) after 1m0s
[14:48:40.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:107:0)
[14:48:40.259] Timer tick, asking Buildbucket for the build status
[14:48:40.371] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:40.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:108:0) after 1m0s
[14:49:40.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:108:0)
[14:49:40.391] Timer tick, asking Buildbucket for the build status
[14:49:40.460] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:40.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:109:0) after 1m0s
[14:50:40.452] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:109:0)
[14:50:40.452] Timer tick, asking Buildbucket for the build status
[14:50:40.578] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:40.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:110:0) after 1m0s
[14:51:40.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:110:0)
[14:51:40.622] Timer tick, asking Buildbucket for the build status
[14:51:40.850] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:40.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:111:0) after 1m0s
[14:52:40.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:111:0)
[14:52:40.836] Timer tick, asking Buildbucket for the build status
[14:52:40.879] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:40.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:112:0) after 1m0s
[14:53:40.940] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:112:0)
[14:53:40.940] Timer tick, asking Buildbucket for the build status
[14:53:40.999] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:40.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:113:0) after 1m0s
[14:54:41.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:113:0)
[14:54:41.026] Timer tick, asking Buildbucket for the build status
[14:54:41.111] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:41.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:114:0) after 1m0s
[14:55:41.126] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:114:0)
[14:55:41.126] Timer tick, asking Buildbucket for the build status
[14:55:41.479] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:41.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:115:0) after 1m0s
[14:56:41.500] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:115:0)
[14:56:41.500] Timer tick, asking Buildbucket for the build status
[14:56:41.539] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:41.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:116:0) after 1m0s
[14:57:41.573] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:116:0)
[14:57:41.573] Timer tick, asking Buildbucket for the build status
[14:57:41.642] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:41.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:117:0) after 1m0s
[14:58:41.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:117:0)
[14:58:41.628] Timer tick, asking Buildbucket for the build status
[14:58:41.771] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:41.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:118:0) after 1m0s
[14:59:41.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:118:0)
[14:59:41.796] Timer tick, asking Buildbucket for the build status
[14:59:41.985] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:41.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:119:0) after 1m0s
[15:00:42.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:119:0)
[15:00:42.200] Timer tick, asking Buildbucket for the build status
[15:00:42.472] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:42.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:120:0) after 1m0s
[15:01:42.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:120:0)
[15:01:42.482] Timer tick, asking Buildbucket for the build status
[15:01:42.598] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:42.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:121:0) after 1m0s
[15:02:42.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:121:0)
[15:02:42.616] Timer tick, asking Buildbucket for the build status
[15:02:42.678] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:42.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:122:0) after 1m0s
[15:03:42.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:122:0)
[15:03:42.779] Timer tick, asking Buildbucket for the build status
[15:03:42.876] Build 8915383663426491456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:42.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080813725455335248:123:0) after 1m0s
[15:04:32.026] Received PubSub notification, asking Buildbucket for the build status
[15:04:32.149] Build 8915383663426491456: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:04:32.149] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1556031871649563",
"created_by": "project:fuchsia",
"created_ts": "1556024601490567",
"failure_reason": "BUILD_FAILURE",
"id": "8915383663426491456",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"652168a99b5cdc1fb420b1224c8c2d42ad2b7465\"}}",
"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-c-v1d1\", \"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\": \"652168a99b5cdc1fb420b1224c8c2d42ad2b7465\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-c-v1d1\"], \"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\": [\"4267-53b17d5\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1556024635454421",
"status": "COMPLETED",
"status_changed_ts": "1556024635989531",
"tags": [
"builder:clang",
"buildset:commit/git/652168a99b5cdc1fb420b1224c8c2d42ad2b7465",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/652168a99b5cdc1fb420b1224c8c2d42ad2b7465",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080813725455335248",
"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/8915383663426491456/+/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:44631cb7ef395110",
"user_agent:luci-scheduler"
],
"updated_ts": "1556031871695907",
"url": "https://ci.chromium.org/b/8915383663426491456",
"utcnow_ts": "1556031872084781"
}
}
[15:04:32.149] Invocation finished in 2h1m11.977693497s with status FAILED