[21:38:26.788] New invocation is queued and will start shortly
[21:38:27.859] Starting the invocation (attempt 1)
[21:38:27.895] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:38:27.895] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:38:27.895] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078969378207629264",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"aeeeb37e373700350472d40cf0a0969b070be0a0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078969378207629264",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/aeeeb37e373700350472d40cf0a0969b070be0a0",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/aeeeb37e373700350472d40cf0a0969b070be0a0",
"gitiles_ref:refs/heads/master"
]
}
[21:38:28.497] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557783508004284",
"id": "8913539316269041424",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"aeeeb37e373700350472d40cf0a0969b070be0a0\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557783508336778",
"tags": [
"builder:clang",
"buildset:commit/git/aeeeb37e373700350472d40cf0a0969b070be0a0",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/aeeeb37e373700350472d40cf0a0969b070be0a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078969378207629264",
"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/8913539316269041424/+/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": "1557783508336879",
"url": "https://ci.chromium.org/b/8913539316269041424",
"utcnow_ts": "1557783508478959"
}
}
[21:38:28.497] Task URL: https://ci.chromium.org/b/8913539316269041424
[21:38:28.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:2:0) after 1m0s
[21:38:34.634] Received PubSub notification, asking Buildbucket for the build status
[21:38:34.851] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:28.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:2:0)
[21:39:28.469] Timer tick, asking Buildbucket for the build status
[21:39:28.580] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:28.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:4:0) after 1m0s
[21:40:28.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:4:0)
[21:40:28.610] Timer tick, asking Buildbucket for the build status
[21:40:28.856] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:28.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:5:0) after 1m0s
[21:41:28.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:5:0)
[21:41:28.965] Timer tick, asking Buildbucket for the build status
[21:41:29.173] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:29.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:6:0) after 1m0s
[21:42:29.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:6:0)
[21:42:29.247] Timer tick, asking Buildbucket for the build status
[21:42:29.305] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:29.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:7:0) after 1m0s
[21:43:29.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:7:0)
[21:43:29.336] Timer tick, asking Buildbucket for the build status
[21:43:29.376] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:29.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:8:0) after 1m0s
[21:44:29.376] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:8:0)
[21:44:29.376] Timer tick, asking Buildbucket for the build status
[21:44:29.480] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:29.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:9:0) after 1m0s
[21:45:29.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:9:0)
[21:45:29.493] Timer tick, asking Buildbucket for the build status
[21:45:29.603] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:29.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:10:0) after 1m0s
[21:46:29.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:10:0)
[21:46:29.596] Timer tick, asking Buildbucket for the build status
[21:46:29.825] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:29.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:11:0) after 1m0s
[21:47:29.844] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:11:0)
[21:47:29.844] Timer tick, asking Buildbucket for the build status
[21:47:29.904] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:29.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:12:0) after 1m0s
[21:48:29.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:12:0)
[21:48:29.988] Timer tick, asking Buildbucket for the build status
[21:48:30.053] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:30.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:13:0) after 1m0s
[21:49:30.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:13:0)
[21:49:30.073] Timer tick, asking Buildbucket for the build status
[21:49:30.126] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:30.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:14:0) after 1m0s
[21:50:30.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:14:0)
[21:50:30.102] Timer tick, asking Buildbucket for the build status
[21:50:30.155] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:30.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:15:0) after 1m0s
[21:51:30.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:15:0)
[21:51:30.189] Timer tick, asking Buildbucket for the build status
[21:51:30.344] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:30.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:16:0) after 1m0s
[21:52:30.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:16:0)
[21:52:30.475] Timer tick, asking Buildbucket for the build status
[21:52:30.522] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:30.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:17:0) after 1m0s
[21:53:30.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:17:0)
[21:53:30.558] Timer tick, asking Buildbucket for the build status
[21:53:30.650] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:30.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:18:0) after 1m0s
[21:54:30.670] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:18:0)
[21:54:30.670] Timer tick, asking Buildbucket for the build status
[21:54:30.712] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:30.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:19:0) after 1m0s
[21:55:30.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:19:0)
[21:55:30.907] Timer tick, asking Buildbucket for the build status
[21:55:30.969] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:30.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:20:0) after 1m0s
[21:56:30.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:20:0)
[21:56:30.997] Timer tick, asking Buildbucket for the build status
[21:56:31.085] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:31.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:21:0) after 1m0s
[21:57:31.142] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:21:0)
[21:57:31.142] Timer tick, asking Buildbucket for the build status
[21:57:31.253] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:31.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:22:0) after 1m0s
[21:58:31.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:22:0)
[21:58:31.248] Timer tick, asking Buildbucket for the build status
[21:58:31.295] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:31.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:23:0) after 1m0s
[21:59:31.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:23:0)
[21:59:31.314] Timer tick, asking Buildbucket for the build status
[21:59:31.475] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:31.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:24:0) after 1m0s
[22:00:31.529] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:24:0)
[22:00:31.529] Timer tick, asking Buildbucket for the build status
[22:00:31.611] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:31.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:25:0) after 1m0s
[22:01:31.633] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:25:0)
[22:01:31.633] Timer tick, asking Buildbucket for the build status
[22:01:34.270] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:34.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:26:0) after 1m0s
[22:02:34.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:26:0)
[22:02:34.293] Timer tick, asking Buildbucket for the build status
[22:02:34.348] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:34.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:27:0) after 1m0s
[22:03:34.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:27:0)
[22:03:34.342] Timer tick, asking Buildbucket for the build status
[22:03:34.411] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:34.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:28:0) after 1m0s
[22:04:34.437] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:28:0)
[22:04:34.437] Timer tick, asking Buildbucket for the build status
[22:04:34.731] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:34.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:29:0) after 1m0s
[22:05:34.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:29:0)
[22:05:34.757] Timer tick, asking Buildbucket for the build status
[22:05:34.893] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:34.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:30:0) after 1m0s
[22:06:34.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:30:0)
[22:06:34.975] Timer tick, asking Buildbucket for the build status
[22:06:35.109] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:35.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:31:0) after 1m0s
[22:07:35.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:31:0)
[22:07:35.134] Timer tick, asking Buildbucket for the build status
[22:07:35.213] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:35.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:32:0) after 1m0s
[22:08:35.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:32:0)
[22:08:35.278] Timer tick, asking Buildbucket for the build status
[22:08:35.785] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:35.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:33:0) after 1m0s
[22:09:35.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:33:0)
[22:09:35.807] Timer tick, asking Buildbucket for the build status
[22:09:36.029] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:36.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:34:0) after 1m0s
[22:10:36.048] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:34:0)
[22:10:36.048] Timer tick, asking Buildbucket for the build status
[22:10:36.250] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:36.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:35:0) after 1m0s
[22:11:36.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:35:0)
[22:11:36.327] Timer tick, asking Buildbucket for the build status
[22:11:36.432] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:36.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:36:0) after 1m0s
[22:12:36.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:36:0)
[22:12:36.457] Timer tick, asking Buildbucket for the build status
[22:12:36.609] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:36.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:37:0) after 1m0s
[22:13:36.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:37:0)
[22:13:36.650] Timer tick, asking Buildbucket for the build status
[22:13:36.713] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:36.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:38:0) after 1m0s
[22:14:36.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:38:0)
[22:14:36.730] Timer tick, asking Buildbucket for the build status
[22:14:36.781] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:36.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:39:0) after 1m0s
[22:15:38.165] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:39:0)
[22:15:38.165] Timer tick, asking Buildbucket for the build status
[22:15:38.210] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:38.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:40:0) after 1m0s
[22:16:38.633] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:40:0)
[22:16:38.633] Timer tick, asking Buildbucket for the build status
[22:16:38.682] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:38.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:41:0) after 1m0s
[22:17:38.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:41:0)
[22:17:38.739] Timer tick, asking Buildbucket for the build status
[22:17:38.858] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:38.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:42:0) after 1m0s
[22:18:38.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:42:0)
[22:18:38.877] Timer tick, asking Buildbucket for the build status
[22:18:38.971] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:38.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:43:0) after 1m0s
[22:19:39.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:43:0)
[22:19:39.005] Timer tick, asking Buildbucket for the build status
[22:19:39.747] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:39.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:44:0) after 1m0s
[22:20:39.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:44:0)
[22:20:39.730] Timer tick, asking Buildbucket for the build status
[22:20:39.801] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:39.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:45:0) after 1m0s
[22:21:39.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:45:0)
[22:21:39.828] Timer tick, asking Buildbucket for the build status
[22:21:40.354] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:40.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:46:0) after 1m0s
[22:22:40.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:46:0)
[22:22:40.442] Timer tick, asking Buildbucket for the build status
[22:22:40.584] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:40.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:47:0) after 1m0s
[22:23:40.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:47:0)
[22:23:40.567] Timer tick, asking Buildbucket for the build status
[22:23:40.710] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:40.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:48:0) after 1m0s
[22:24:40.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:48:0)
[22:24:40.731] Timer tick, asking Buildbucket for the build status
[22:24:41.229] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:41.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:49:0) after 1m0s
[22:25:41.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:49:0)
[22:25:41.224] Timer tick, asking Buildbucket for the build status
[22:25:41.304] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:41.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:50:0) after 1m0s
[22:26:41.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:50:0)
[22:26:41.328] Timer tick, asking Buildbucket for the build status
[22:26:41.397] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:41.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:51:0) after 1m0s
[22:27:41.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:51:0)
[22:27:41.401] Timer tick, asking Buildbucket for the build status
[22:27:41.563] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:41.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:52:0) after 1m0s
[22:28:41.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:52:0)
[22:28:41.578] Timer tick, asking Buildbucket for the build status
[22:28:41.848] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:41.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:53:0) after 1m0s
[22:29:41.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:53:0)
[22:29:41.871] Timer tick, asking Buildbucket for the build status
[22:29:42.055] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:42.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:54:0) after 1m0s
[22:30:42.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:54:0)
[22:30:42.105] Timer tick, asking Buildbucket for the build status
[22:30:42.206] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:42.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:55:0) after 1m0s
[22:31:42.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:55:0)
[22:31:42.257] Timer tick, asking Buildbucket for the build status
[22:31:42.308] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:42.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:56:0) after 1m0s
[22:32:42.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:56:0)
[22:32:42.351] Timer tick, asking Buildbucket for the build status
[22:32:42.429] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:42.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:57:0) after 1m0s
[22:33:42.456] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:57:0)
[22:33:42.456] Timer tick, asking Buildbucket for the build status
[22:33:43.192] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:43.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:58:0) after 1m0s
[22:34:43.172] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:58:0)
[22:34:43.172] Timer tick, asking Buildbucket for the build status
[22:34:43.387] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:43.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:59:0) after 1m0s
[22:35:43.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:59:0)
[22:35:43.411] Timer tick, asking Buildbucket for the build status
[22:35:43.562] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:43.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:60:0) after 1m0s
[22:36:43.541] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:60:0)
[22:36:43.541] Timer tick, asking Buildbucket for the build status
[22:36:43.655] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:43.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:61:0) after 1m0s
[22:37:43.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:61:0)
[22:37:43.687] Timer tick, asking Buildbucket for the build status
[22:37:44.316] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:44.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:62:0) after 1m0s
[22:38:44.294] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:62:0)
[22:38:44.294] Timer tick, asking Buildbucket for the build status
[22:38:44.480] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:44.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:63:0) after 1m0s
[22:39:44.504] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:63:0)
[22:39:44.504] Timer tick, asking Buildbucket for the build status
[22:39:44.835] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:44.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:64:0) after 1m0s
[22:40:44.860] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:64:0)
[22:40:44.860] Timer tick, asking Buildbucket for the build status
[22:40:45.012] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:45.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:65:0) after 1m0s
[22:41:44.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:65:0)
[22:41:44.998] Timer tick, asking Buildbucket for the build status
[22:41:45.045] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:45.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:66:0) after 1m0s
[22:42:45.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:66:0)
[22:42:45.053] Timer tick, asking Buildbucket for the build status
[22:42:45.138] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:45.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:67:0) after 1m0s
[22:43:45.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:67:0)
[22:43:45.173] Timer tick, asking Buildbucket for the build status
[22:43:45.553] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:45.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:68:0) after 1m0s
[22:44:45.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:68:0)
[22:44:45.580] Timer tick, asking Buildbucket for the build status
[22:44:45.768] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:45.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:69:0) after 1m0s
[22:45:45.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:69:0)
[22:45:45.760] Timer tick, asking Buildbucket for the build status
[22:45:46.234] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:46.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:70:0) after 1m0s
[22:46:46.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:70:0)
[22:46:46.298] Timer tick, asking Buildbucket for the build status
[22:46:46.441] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:46.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:71:0) after 1m0s
[22:47:46.469] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:71:0)
[22:47:46.469] Timer tick, asking Buildbucket for the build status
[22:47:47.041] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:47.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:72:0) after 1m0s
[22:48:47.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:72:0)
[22:48:47.031] Timer tick, asking Buildbucket for the build status
[22:48:47.141] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:47.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:73:0) after 1m0s
[22:49:47.141] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:73:0)
[22:49:47.141] Timer tick, asking Buildbucket for the build status
[22:49:47.537] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:47.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:74:0) after 1m0s
[22:50:47.550] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:74:0)
[22:50:47.550] Timer tick, asking Buildbucket for the build status
[22:50:47.592] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:47.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:75:0) after 1m0s
[22:51:47.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:75:0)
[22:51:47.646] Timer tick, asking Buildbucket for the build status
[22:51:47.948] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:47.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:76:0) after 1m0s
[22:52:48.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:76:0)
[22:52:48.016] Timer tick, asking Buildbucket for the build status
[22:52:48.106] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:48.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:77:0) after 1m0s
[22:53:48.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:77:0)
[22:53:48.133] Timer tick, asking Buildbucket for the build status
[22:53:48.629] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:48.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:78:0) after 1m0s
[22:54:48.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:78:0)
[22:54:48.701] Timer tick, asking Buildbucket for the build status
[22:54:48.910] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:48.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:79:0) after 1m0s
[22:55:48.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:79:0)
[22:55:48.929] Timer tick, asking Buildbucket for the build status
[22:55:49.158] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:49.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:80:0) after 1m0s
[22:56:49.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:80:0)
[22:56:49.149] Timer tick, asking Buildbucket for the build status
[22:56:49.744] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:49.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:81:0) after 1m0s
[22:57:49.763] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:81:0)
[22:57:49.763] Timer tick, asking Buildbucket for the build status
[22:57:49.825] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:49.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:82:0) after 1m0s
[22:58:49.848] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:82:0)
[22:58:49.848] Timer tick, asking Buildbucket for the build status
[22:58:50.026] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:50.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:83:0) after 1m0s
[22:59:50.096] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:83:0)
[22:59:50.096] Timer tick, asking Buildbucket for the build status
[22:59:50.215] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:50.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:84:0) after 1m0s
[23:00:50.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:84:0)
[23:00:50.194] Timer tick, asking Buildbucket for the build status
[23:00:50.438] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:50.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:85:0) after 1m0s
[23:01:50.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:85:0)
[23:01:50.481] Timer tick, asking Buildbucket for the build status
[23:01:50.703] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:50.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:86:0) after 1m0s
[23:02:50.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:86:0)
[23:02:50.705] Timer tick, asking Buildbucket for the build status
[23:02:51.012] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:51.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:87:0) after 1m0s
[23:03:51.024] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:87:0)
[23:03:51.024] Timer tick, asking Buildbucket for the build status
[23:03:51.076] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:51.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:88:0) after 1m0s
[23:04:51.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:88:0)
[23:04:51.107] Timer tick, asking Buildbucket for the build status
[23:04:51.197] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:51.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:89:0) after 1m0s
[23:05:51.221] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:89:0)
[23:05:51.221] Timer tick, asking Buildbucket for the build status
[23:05:51.317] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:51.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:90:0) after 1m0s
[23:06:51.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:90:0)
[23:06:51.392] Timer tick, asking Buildbucket for the build status
[23:06:51.583] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:51.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:91:0) after 1m0s
[23:07:51.744] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:91:0)
[23:07:51.744] Timer tick, asking Buildbucket for the build status
[23:07:52.283] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:52.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:92:0) after 1m0s
[23:08:52.326] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:92:0)
[23:08:52.326] Timer tick, asking Buildbucket for the build status
[23:08:52.371] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:52.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:93:0) after 1m0s
[23:09:52.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:93:0)
[23:09:52.361] Timer tick, asking Buildbucket for the build status
[23:09:52.552] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:52.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:94:0) after 1m0s
[23:10:52.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:94:0)
[23:10:52.581] Timer tick, asking Buildbucket for the build status
[23:10:52.655] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:52.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:95:0) after 1m0s
[23:11:52.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:95:0)
[23:11:52.640] Timer tick, asking Buildbucket for the build status
[23:11:52.694] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:52.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:96:0) after 1m0s
[23:12:52.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:96:0)
[23:12:52.716] Timer tick, asking Buildbucket for the build status
[23:12:53.215] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:53.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:97:0) after 1m0s
[23:13:53.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:97:0)
[23:13:53.249] Timer tick, asking Buildbucket for the build status
[23:13:53.824] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:53.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:98:0) after 1m0s
[23:14:53.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:98:0)
[23:14:53.830] Timer tick, asking Buildbucket for the build status
[23:14:53.904] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:53.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:99:0) after 1m0s
[23:15:53.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:99:0)
[23:15:53.929] Timer tick, asking Buildbucket for the build status
[23:15:54.013] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:54.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:100:0) after 1m0s
[23:16:54.043] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:100:0)
[23:16:54.043] Timer tick, asking Buildbucket for the build status
[23:16:54.112] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:54.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:101:0) after 1m0s
[23:17:54.108] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:101:0)
[23:17:54.108] Timer tick, asking Buildbucket for the build status
[23:17:54.217] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:54.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:102:0) after 1m0s
[23:18:54.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:102:0)
[23:18:54.231] Timer tick, asking Buildbucket for the build status
[23:18:54.305] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:54.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:103:0) after 1m0s
[23:19:54.354] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:103:0)
[23:19:54.354] Timer tick, asking Buildbucket for the build status
[23:19:54.498] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:54.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:104:0) after 1m0s
[23:20:54.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:104:0)
[23:20:54.524] Timer tick, asking Buildbucket for the build status
[23:20:54.574] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:54.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:105:0) after 1m0s
[23:21:54.733] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:105:0)
[23:21:54.733] Timer tick, asking Buildbucket for the build status
[23:21:55.033] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:55.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:106:0) after 1m0s
[23:22:55.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:106:0)
[23:22:55.027] Timer tick, asking Buildbucket for the build status
[23:22:55.238] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:55.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:107:0) after 1m0s
[23:23:55.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:107:0)
[23:23:55.273] Timer tick, asking Buildbucket for the build status
[23:23:55.373] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:55.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:108:0) after 1m0s
[23:24:55.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:108:0)
[23:24:55.395] Timer tick, asking Buildbucket for the build status
[23:24:55.569] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:55.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:109:0) after 1m0s
[23:25:55.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:109:0)
[23:25:55.591] Timer tick, asking Buildbucket for the build status
[23:25:55.783] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:55.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:110:0) after 1m0s
[23:26:55.785] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:110:0)
[23:26:55.785] Timer tick, asking Buildbucket for the build status
[23:26:55.837] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:55.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:111:0) after 1m0s
[23:27:55.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:111:0)
[23:27:55.862] Timer tick, asking Buildbucket for the build status
[23:27:55.972] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:55.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:112:0) after 1m0s
[23:28:55.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:112:0)
[23:28:55.998] Timer tick, asking Buildbucket for the build status
[23:28:56.060] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:56.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:113:0) after 1m0s
[23:29:56.085] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:113:0)
[23:29:56.085] Timer tick, asking Buildbucket for the build status
[23:29:56.131] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:56.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:114:0) after 1m0s
[23:30:56.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:114:0)
[23:30:56.156] Timer tick, asking Buildbucket for the build status
[23:30:56.292] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:56.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:115:0) after 1m0s
[23:31:56.324] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:115:0)
[23:31:56.324] Timer tick, asking Buildbucket for the build status
[23:31:56.395] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:56.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:116:0) after 1m0s
[23:32:56.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:116:0)
[23:32:56.398] Timer tick, asking Buildbucket for the build status
[23:32:56.546] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:56.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:117:0) after 1m0s
[23:33:56.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:117:0)
[23:33:56.564] Timer tick, asking Buildbucket for the build status
[23:33:56.716] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:56.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:118:0) after 1m0s
[23:34:56.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:118:0)
[23:34:56.717] Timer tick, asking Buildbucket for the build status
[23:34:56.855] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:56.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:119:0) after 1m0s
[23:35:56.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:119:0)
[23:35:56.883] Timer tick, asking Buildbucket for the build status
[23:35:56.949] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:56.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:120:0) after 1m0s
[23:36:56.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:120:0)
[23:36:56.966] Timer tick, asking Buildbucket for the build status
[23:36:57.101] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:57.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:121:0) after 1m0s
[23:37:57.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:121:0)
[23:37:57.088] Timer tick, asking Buildbucket for the build status
[23:37:57.187] Build 8913539316269041424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:57.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078969378207629264:122:0) after 1m0s
[23:38:57.174] Received PubSub notification, asking Buildbucket for the build status
[23:38:57.231] Build 8913539316269041424: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:38:57.231] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557790736479561",
"created_by": "project:fuchsia",
"created_ts": "1557783508004284",
"failure_reason": "BUILD_FAILURE",
"id": "8913539316269041424",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"aeeeb37e373700350472d40cf0a0969b070be0a0\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-b-1-5mek\", \"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\": \"aeeeb37e373700350472d40cf0a0969b070be0a0\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-b-1-5mek\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4293-d3520ea\"], \"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": "1557783513858168",
"status": "COMPLETED",
"status_changed_ts": "1557783514273949",
"tags": [
"builder:clang",
"buildset:commit/git/aeeeb37e373700350472d40cf0a0969b070be0a0",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/aeeeb37e373700350472d40cf0a0969b070be0a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078969378207629264",
"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/8913539316269041424/+/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:44cbf38044fadf10",
"user_agent:luci-scheduler"
],
"updated_ts": "1557790736606686",
"url": "https://ci.chromium.org/b/8913539316269041424",
"utcnow_ts": "1557790737225570"
}
}
[23:38:57.231] Invocation finished in 2h0m30.45958804s with status FAILED