[16:15:34.292] New invocation is queued and will start shortly
[16:15:35.377] Starting the invocation (attempt 1)
[16:15:35.421] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:15:35.421] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:15:35.421] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081254615906282688",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"b2c9fc02d526dcc8f59ca1594a9d7b3fd965c3c6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081254615906282688",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/b2c9fc02d526dcc8f59ca1594a9d7b3fd965c3c6",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b2c9fc02d526dcc8f59ca1594a9d7b3fd965c3c6",
"gitiles_ref:refs/heads/master"
]
}
[16:15:36.241] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555604135520203",
"id": "8915824553951500848",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b2c9fc02d526dcc8f59ca1594a9d7b3fd965c3c6\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555604136065546",
"tags": [
"builder:clang",
"buildset:commit/git/b2c9fc02d526dcc8f59ca1594a9d7b3fd965c3c6",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b2c9fc02d526dcc8f59ca1594a9d7b3fd965c3c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081254615906282688",
"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/8915824553951500848/+/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": "1555604136065682",
"url": "https://ci.chromium.org/b/8915824553951500848",
"utcnow_ts": "1555604136230865"
}
}
[16:15:36.241] Task URL: https://ci.chromium.org/b/8915824553951500848
[16:15:36.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:2:0) after 1m0s
[16:15:57.747] Received PubSub notification, asking Buildbucket for the build status
[16:15:57.805] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:36.263] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:2:0)
[16:16:36.263] Timer tick, asking Buildbucket for the build status
[16:16:36.367] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:36.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:4:0) after 1m0s
[16:17:36.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:4:0)
[16:17:36.374] Timer tick, asking Buildbucket for the build status
[16:17:36.492] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:36.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:5:0) after 1m0s
[16:18:36.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:5:0)
[16:18:36.524] Timer tick, asking Buildbucket for the build status
[16:18:36.571] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:36.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:6:0) after 1m0s
[16:19:36.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:6:0)
[16:19:36.577] Timer tick, asking Buildbucket for the build status
[16:19:36.638] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:36.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:7:0) after 1m0s
[16:20:36.659] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:7:0)
[16:20:36.659] Timer tick, asking Buildbucket for the build status
[16:20:36.813] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:36.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:8:0) after 1m0s
[16:21:36.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:8:0)
[16:21:36.826] Timer tick, asking Buildbucket for the build status
[16:21:36.900] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:36.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:9:0) after 1m0s
[16:22:36.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:9:0)
[16:22:36.911] Timer tick, asking Buildbucket for the build status
[16:22:37.192] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:37.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:10:0) after 1m0s
[16:23:37.201] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:10:0)
[16:23:37.201] Timer tick, asking Buildbucket for the build status
[16:23:37.248] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:37.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:11:0) after 1m0s
[16:24:37.256] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:11:0)
[16:24:37.256] Timer tick, asking Buildbucket for the build status
[16:24:37.315] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:37.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:12:0) after 1m0s
[16:25:37.337] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:12:0)
[16:25:37.337] Timer tick, asking Buildbucket for the build status
[16:25:37.488] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:37.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:13:0) after 1m0s
[16:26:37.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:13:0)
[16:26:37.509] Timer tick, asking Buildbucket for the build status
[16:26:37.549] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:37.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:14:0) after 1m0s
[16:27:37.573] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:14:0)
[16:27:37.573] Timer tick, asking Buildbucket for the build status
[16:27:37.628] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:37.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:15:0) after 1m0s
[16:28:37.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:15:0)
[16:28:37.676] Timer tick, asking Buildbucket for the build status
[16:28:37.717] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:37.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:16:0) after 1m0s
[16:29:37.816] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:16:0)
[16:29:37.816] Timer tick, asking Buildbucket for the build status
[16:29:37.917] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:37.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:17:0) after 1m0s
[16:30:37.923] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:17:0)
[16:30:37.923] Timer tick, asking Buildbucket for the build status
[16:30:37.988] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:37.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:18:0) after 1m0s
[16:31:38.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:18:0)
[16:31:38.008] Timer tick, asking Buildbucket for the build status
[16:31:38.055] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:38.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:19:0) after 1m0s
[16:32:38.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:19:0)
[16:32:38.098] Timer tick, asking Buildbucket for the build status
[16:32:38.138] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:38.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:20:0) after 1m0s
[16:33:38.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:20:0)
[16:33:38.177] Timer tick, asking Buildbucket for the build status
[16:33:38.231] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:38.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:21:0) after 1m0s
[16:34:38.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:21:0)
[16:34:38.249] Timer tick, asking Buildbucket for the build status
[16:34:38.309] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:38.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:22:0) after 1m0s
[16:35:38.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:22:0)
[16:35:38.291] Timer tick, asking Buildbucket for the build status
[16:35:38.511] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:38.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:23:0) after 1m0s
[16:36:38.531] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:23:0)
[16:36:38.531] Timer tick, asking Buildbucket for the build status
[16:36:38.626] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:38.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:24:0) after 1m0s
[16:37:38.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:24:0)
[16:37:38.600] Timer tick, asking Buildbucket for the build status
[16:37:38.662] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:38.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:25:0) after 1m0s
[16:38:38.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:25:0)
[16:38:38.682] Timer tick, asking Buildbucket for the build status
[16:38:38.745] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:38.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:26:0) after 1m0s
[16:39:38.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:26:0)
[16:39:38.739] Timer tick, asking Buildbucket for the build status
[16:39:38.839] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:38.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:27:0) after 1m0s
[16:40:38.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:27:0)
[16:40:38.849] Timer tick, asking Buildbucket for the build status
[16:40:38.960] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:38.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:28:0) after 1m0s
[16:41:38.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:28:0)
[16:41:38.979] Timer tick, asking Buildbucket for the build status
[16:41:39.120] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:39.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:29:0) after 1m0s
[16:42:39.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:29:0)
[16:42:39.093] Timer tick, asking Buildbucket for the build status
[16:42:39.268] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:39.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:30:0) after 1m0s
[16:43:39.258] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:30:0)
[16:43:39.258] Timer tick, asking Buildbucket for the build status
[16:43:39.392] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:39.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:31:0) after 1m0s
[16:44:39.413] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:31:0)
[16:44:39.413] Timer tick, asking Buildbucket for the build status
[16:44:39.482] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:39.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:32:0) after 1m0s
[16:45:39.500] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:32:0)
[16:45:39.500] Timer tick, asking Buildbucket for the build status
[16:45:39.601] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:39.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:33:0) after 1m0s
[16:46:39.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:33:0)
[16:46:39.687] Timer tick, asking Buildbucket for the build status
[16:46:39.745] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:39.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:34:0) after 1m0s
[16:47:39.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:34:0)
[16:47:39.769] Timer tick, asking Buildbucket for the build status
[16:47:39.840] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:39.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:35:0) after 1m0s
[16:48:39.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:35:0)
[16:48:39.830] Timer tick, asking Buildbucket for the build status
[16:48:39.987] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:39.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:36:0) after 1m0s
[16:49:40.011] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:36:0)
[16:49:40.011] Timer tick, asking Buildbucket for the build status
[16:49:40.059] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:40.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:37:0) after 1m0s
[16:50:40.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:37:0)
[16:50:40.086] Timer tick, asking Buildbucket for the build status
[16:50:40.176] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:40.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:38:0) after 1m0s
[16:51:40.195] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:38:0)
[16:51:40.195] Timer tick, asking Buildbucket for the build status
[16:51:40.271] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:40.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:39:0) after 1m0s
[16:52:40.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:39:0)
[16:52:40.273] Timer tick, asking Buildbucket for the build status
[16:52:40.317] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:40.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:40:0) after 1m0s
[16:53:40.297] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:40:0)
[16:53:40.297] Timer tick, asking Buildbucket for the build status
[16:53:40.454] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:40.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:41:0) after 1m0s
[16:54:40.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:41:0)
[16:54:40.483] Timer tick, asking Buildbucket for the build status
[16:54:40.555] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:40.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:42:0) after 1m0s
[16:55:40.574] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:42:0)
[16:55:40.574] Timer tick, asking Buildbucket for the build status
[16:55:40.666] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:40.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:43:0) after 1m0s
[16:56:40.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:43:0)
[16:56:40.674] Timer tick, asking Buildbucket for the build status
[16:56:40.872] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:40.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:44:0) after 1m0s
[16:57:40.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:44:0)
[16:57:40.894] Timer tick, asking Buildbucket for the build status
[16:57:41.063] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:41.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:45:0) after 1m0s
[16:58:41.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:45:0)
[16:58:41.053] Timer tick, asking Buildbucket for the build status
[16:58:41.116] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:41.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:46:0) after 1m0s
[16:59:41.143] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:46:0)
[16:59:41.143] Timer tick, asking Buildbucket for the build status
[16:59:41.264] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:41.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:47:0) after 1m0s
[17:00:41.292] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:47:0)
[17:00:41.292] Timer tick, asking Buildbucket for the build status
[17:00:41.371] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:41.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:48:0) after 1m0s
[17:01:41.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:48:0)
[17:01:41.382] Timer tick, asking Buildbucket for the build status
[17:01:41.450] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:41.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:49:0) after 1m0s
[17:02:41.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:49:0)
[17:02:41.439] Timer tick, asking Buildbucket for the build status
[17:02:41.534] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:41.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:50:0) after 1m0s
[17:03:41.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:50:0)
[17:03:41.568] Timer tick, asking Buildbucket for the build status
[17:03:41.626] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:41.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:51:0) after 1m0s
[17:04:41.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:51:0)
[17:04:41.648] Timer tick, asking Buildbucket for the build status
[17:04:41.712] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:41.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:52:0) after 1m0s
[17:05:41.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:52:0)
[17:05:41.738] Timer tick, asking Buildbucket for the build status
[17:05:41.803] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:41.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:53:0) after 1m0s
[17:06:41.834] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:53:0)
[17:06:41.834] Timer tick, asking Buildbucket for the build status
[17:06:41.929] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:41.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:54:0) after 1m0s
[17:07:42.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:54:0)
[17:07:42.026] Timer tick, asking Buildbucket for the build status
[17:07:42.064] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:42.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:55:0) after 1m0s
[17:08:42.118] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:55:0)
[17:08:42.118] Timer tick, asking Buildbucket for the build status
[17:08:42.178] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:42.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:56:0) after 1m0s
[17:09:42.202] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:56:0)
[17:09:42.202] Timer tick, asking Buildbucket for the build status
[17:09:42.255] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:42.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:57:0) after 1m0s
[17:10:42.275] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:57:0)
[17:10:42.275] Timer tick, asking Buildbucket for the build status
[17:10:42.532] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:42.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:58:0) after 1m0s
[17:11:42.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:58:0)
[17:11:42.553] Timer tick, asking Buildbucket for the build status
[17:11:42.719] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:42.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:59:0) after 1m0s
[17:12:42.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:59:0)
[17:12:42.739] Timer tick, asking Buildbucket for the build status
[17:12:42.857] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:42.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:60:0) after 1m0s
[17:13:42.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:60:0)
[17:13:42.902] Timer tick, asking Buildbucket for the build status
[17:13:42.957] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:42.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:61:0) after 1m0s
[17:14:42.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:61:0)
[17:14:42.966] Timer tick, asking Buildbucket for the build status
[17:14:43.032] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:43.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:62:0) after 1m0s
[17:15:43.051] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:62:0)
[17:15:43.051] Timer tick, asking Buildbucket for the build status
[17:15:43.137] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:43.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:63:0) after 1m0s
[17:16:43.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:63:0)
[17:16:43.127] Timer tick, asking Buildbucket for the build status
[17:16:43.171] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:43.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:64:0) after 1m0s
[17:17:43.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:64:0)
[17:17:43.192] Timer tick, asking Buildbucket for the build status
[17:17:43.232] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:43.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:65:0) after 1m0s
[17:18:43.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:65:0)
[17:18:43.296] Timer tick, asking Buildbucket for the build status
[17:18:43.362] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:43.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:66:0) after 1m0s
[17:19:43.373] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:66:0)
[17:19:43.373] Timer tick, asking Buildbucket for the build status
[17:19:43.415] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:43.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:67:0) after 1m0s
[17:20:43.409] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:67:0)
[17:20:43.409] Timer tick, asking Buildbucket for the build status
[17:20:43.451] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:43.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:68:0) after 1m0s
[17:21:43.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:68:0)
[17:21:43.428] Timer tick, asking Buildbucket for the build status
[17:21:43.467] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:43.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:69:0) after 1m0s
[17:22:43.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:69:0)
[17:22:43.486] Timer tick, asking Buildbucket for the build status
[17:22:43.554] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:43.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:70:0) after 1m0s
[17:23:43.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:70:0)
[17:23:43.576] Timer tick, asking Buildbucket for the build status
[17:23:43.628] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:43.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:71:0) after 1m0s
[17:24:43.632] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:71:0)
[17:24:43.632] Timer tick, asking Buildbucket for the build status
[17:24:43.691] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:43.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:72:0) after 1m0s
[17:25:43.712] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:72:0)
[17:25:43.712] Timer tick, asking Buildbucket for the build status
[17:25:43.861] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:43.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:73:0) after 1m0s
[17:26:43.853] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:73:0)
[17:26:43.853] Timer tick, asking Buildbucket for the build status
[17:26:43.926] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:43.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:74:0) after 1m0s
[17:27:43.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:74:0)
[17:27:43.945] Timer tick, asking Buildbucket for the build status
[17:27:44.002] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:44.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:75:0) after 1m0s
[17:28:44.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:75:0)
[17:28:44.037] Timer tick, asking Buildbucket for the build status
[17:28:44.162] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:44.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:76:0) after 1m0s
[17:29:44.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:76:0)
[17:29:44.185] Timer tick, asking Buildbucket for the build status
[17:29:44.237] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:44.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:77:0) after 1m0s
[17:30:44.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:77:0)
[17:30:44.259] Timer tick, asking Buildbucket for the build status
[17:30:44.302] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:44.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:78:0) after 1m0s
[17:31:44.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:78:0)
[17:31:44.327] Timer tick, asking Buildbucket for the build status
[17:31:44.461] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:44.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:79:0) after 1m0s
[17:32:44.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:79:0)
[17:32:44.442] Timer tick, asking Buildbucket for the build status
[17:32:44.501] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:44.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:80:0) after 1m0s
[17:33:44.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:80:0)
[17:33:44.521] Timer tick, asking Buildbucket for the build status
[17:33:44.628] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:44.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:81:0) after 1m0s
[17:34:44.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:81:0)
[17:34:44.625] Timer tick, asking Buildbucket for the build status
[17:34:44.669] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:44.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:82:0) after 1m0s
[17:35:44.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:82:0)
[17:35:44.689] Timer tick, asking Buildbucket for the build status
[17:35:44.772] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:44.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:83:0) after 1m0s
[17:36:44.839] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:83:0)
[17:36:44.839] Timer tick, asking Buildbucket for the build status
[17:36:44.884] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:44.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:84:0) after 1m0s
[17:37:44.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:84:0)
[17:37:44.869] Timer tick, asking Buildbucket for the build status
[17:37:45.243] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:45.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:85:0) after 1m0s
[17:38:45.267] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:85:0)
[17:38:45.267] Timer tick, asking Buildbucket for the build status
[17:38:45.381] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:45.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:86:0) after 1m0s
[17:39:45.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:86:0)
[17:39:45.374] Timer tick, asking Buildbucket for the build status
[17:39:45.440] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:45.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:87:0) after 1m0s
[17:40:45.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:87:0)
[17:40:45.464] Timer tick, asking Buildbucket for the build status
[17:40:45.504] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:45.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:88:0) after 1m0s
[17:41:45.528] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:88:0)
[17:41:45.528] Timer tick, asking Buildbucket for the build status
[17:41:45.633] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:45.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:89:0) after 1m0s
[17:42:45.669] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:89:0)
[17:42:45.669] Timer tick, asking Buildbucket for the build status
[17:42:45.713] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:45.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:90:0) after 1m0s
[17:43:45.740] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:90:0)
[17:43:45.740] Timer tick, asking Buildbucket for the build status
[17:43:45.781] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:45.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:91:0) after 1m0s
[17:44:45.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:91:0)
[17:44:45.802] Timer tick, asking Buildbucket for the build status
[17:44:45.884] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:45.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:92:0) after 1m0s
[17:45:45.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:92:0)
[17:45:45.892] Timer tick, asking Buildbucket for the build status
[17:45:46.083] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:46.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:93:0) after 1m0s
[17:46:46.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:93:0)
[17:46:46.105] Timer tick, asking Buildbucket for the build status
[17:46:46.193] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:46.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:94:0) after 1m0s
[17:47:46.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:94:0)
[17:47:46.212] Timer tick, asking Buildbucket for the build status
[17:47:46.277] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:46.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:95:0) after 1m0s
[17:48:46.353] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:95:0)
[17:48:46.353] Timer tick, asking Buildbucket for the build status
[17:48:46.420] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:46.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:96:0) after 1m0s
[17:49:46.452] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:96:0)
[17:49:46.452] Timer tick, asking Buildbucket for the build status
[17:49:46.489] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:46.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:97:0) after 1m0s
[17:50:46.478] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:97:0)
[17:50:46.478] Timer tick, asking Buildbucket for the build status
[17:50:46.520] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:46.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:98:0) after 1m0s
[17:51:46.532] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:98:0)
[17:51:46.532] Timer tick, asking Buildbucket for the build status
[17:51:46.576] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:46.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:99:0) after 1m0s
[17:52:46.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:99:0)
[17:52:46.619] Timer tick, asking Buildbucket for the build status
[17:52:46.662] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:46.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:100:0) after 1m0s
[17:53:46.744] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:100:0)
[17:53:46.744] Timer tick, asking Buildbucket for the build status
[17:53:46.807] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:46.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:101:0) after 1m0s
[17:54:46.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:101:0)
[17:54:46.832] Timer tick, asking Buildbucket for the build status
[17:54:46.883] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:46.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:102:0) after 1m0s
[17:55:46.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:102:0)
[17:55:46.919] Timer tick, asking Buildbucket for the build status
[17:55:46.962] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:46.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:103:0) after 1m0s
[17:56:46.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:103:0)
[17:56:46.999] Timer tick, asking Buildbucket for the build status
[17:56:47.645] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:47.645] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:104:0) after 1m0s
[17:57:47.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:104:0)
[17:57:47.675] Timer tick, asking Buildbucket for the build status
[17:57:47.726] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:47.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:105:0) after 1m0s
[17:58:47.777] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:105:0)
[17:58:47.777] Timer tick, asking Buildbucket for the build status
[17:58:47.835] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:47.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:106:0) after 1m0s
[17:59:47.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:106:0)
[17:59:47.832] Timer tick, asking Buildbucket for the build status
[17:59:47.870] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:47.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:107:0) after 1m0s
[18:00:47.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:107:0)
[18:00:47.911] Timer tick, asking Buildbucket for the build status
[18:00:48.011] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:48.011] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:108:0) after 1m0s
[18:01:47.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:108:0)
[18:01:47.989] Timer tick, asking Buildbucket for the build status
[18:01:48.030] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:48.030] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:109:0) after 1m0s
[18:02:48.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:109:0)
[18:02:48.093] Timer tick, asking Buildbucket for the build status
[18:02:48.214] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:48.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:110:0) after 1m0s
[18:03:48.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:110:0)
[18:03:48.238] Timer tick, asking Buildbucket for the build status
[18:03:48.323] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:48.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:111:0) after 1m0s
[18:04:48.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:111:0)
[18:04:48.356] Timer tick, asking Buildbucket for the build status
[18:04:48.404] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:48.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:112:0) after 1m0s
[18:05:48.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:112:0)
[18:05:48.399] Timer tick, asking Buildbucket for the build status
[18:05:48.461] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:48.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:113:0) after 1m0s
[18:06:48.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:113:0)
[18:06:48.483] Timer tick, asking Buildbucket for the build status
[18:06:48.559] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:48.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:114:0) after 1m0s
[18:07:48.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:114:0)
[18:07:48.559] Timer tick, asking Buildbucket for the build status
[18:07:48.610] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:48.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:115:0) after 1m0s
[18:08:48.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:115:0)
[18:08:48.622] Timer tick, asking Buildbucket for the build status
[18:08:48.686] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:48.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:116:0) after 1m0s
[18:09:48.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:116:0)
[18:09:48.711] Timer tick, asking Buildbucket for the build status
[18:09:48.747] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:48.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:117:0) after 1m0s
[18:10:48.759] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:117:0)
[18:10:48.759] Timer tick, asking Buildbucket for the build status
[18:10:48.804] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:48.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:118:0) after 1m0s
[18:11:48.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:118:0)
[18:11:48.830] Timer tick, asking Buildbucket for the build status
[18:11:48.874] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:48.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:119:0) after 1m0s
[18:12:48.940] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:119:0)
[18:12:48.940] Timer tick, asking Buildbucket for the build status
[18:12:49.003] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:49.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:120:0) after 1m0s
[18:13:49.024] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:120:0)
[18:13:49.024] Timer tick, asking Buildbucket for the build status
[18:13:49.069] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:49.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:121:0) after 1m0s
[18:14:49.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:121:0)
[18:14:49.093] Timer tick, asking Buildbucket for the build status
[18:14:49.181] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:49.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:122:0) after 1m0s
[18:15:49.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:122:0)
[18:15:49.193] Timer tick, asking Buildbucket for the build status
[18:15:49.277] Build 8915824553951500848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:49.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081254615906282688:123:0) after 1m0s
[18:16:19.217] Received PubSub notification, asking Buildbucket for the build status
[18:16:19.275] Build 8915824553951500848: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:16:19.275] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555611378707996",
"created_by": "project:fuchsia",
"created_ts": "1555604135520203",
"failure_reason": "BUILD_FAILURE",
"id": "8915824553951500848",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b2c9fc02d526dcc8f59ca1594a9d7b3fd965c3c6\"}}",
"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-pn6x\", \"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\": \"b2c9fc02d526dcc8f59ca1594a9d7b3fd965c3c6\", \"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-pn6x\"], \"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\": [\"4260-fb32526\"], \"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": "1555604157146677",
"status": "COMPLETED",
"status_changed_ts": "1555604157498868",
"tags": [
"builder:clang",
"buildset:commit/git/b2c9fc02d526dcc8f59ca1594a9d7b3fd965c3c6",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b2c9fc02d526dcc8f59ca1594a9d7b3fd965c3c6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081254615906282688",
"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/8915824553951500848/+/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:444a0ce9e6bdcb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1555611378892360",
"url": "https://ci.chromium.org/b/8915824553951500848",
"utcnow_ts": "1555611379261849"
}
}
[18:16:19.275] Invocation finished in 2h0m45.00291863s with status FAILED