[02:43:38.549] New invocation is queued and will start shortly
[02:43:39.870] Starting the invocation (attempt 1)
[02:43:39.927] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:43:39.927] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:43:39.927] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9077772416375494240",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"cfe08bc7d68712d8bdb2c205981bbbf15968a19e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9077772416375494240",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/cfe08bc7d68712d8bdb2c205981bbbf15968a19e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/cfe08bc7d68712d8bdb2c205981bbbf15968a19e",
"gitiles_ref:refs/heads/master"
]
}
[02:43:40.622] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558925020041519",
"id": "8912342354143569520",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"cfe08bc7d68712d8bdb2c205981bbbf15968a19e\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558925020498140",
"tags": [
"builder:clang",
"buildset:commit/git/cfe08bc7d68712d8bdb2c205981bbbf15968a19e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/cfe08bc7d68712d8bdb2c205981bbbf15968a19e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077772416375494240",
"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/8912342354143569520/+/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": "1558925020498229",
"url": "https://ci.chromium.org/b/8912342354143569520",
"utcnow_ts": "1558925020612547"
}
}
[02:43:40.622] Task URL: https://ci.chromium.org/b/8912342354143569520
[02:43:40.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:2:0) after 1m0s
[02:44:08.731] Received PubSub notification, asking Buildbucket for the build status
[02:44:08.853] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:40.682] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:2:0)
[02:44:40.682] Timer tick, asking Buildbucket for the build status
[02:44:40.862] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:40.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:4:0) after 1m0s
[02:45:40.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:4:0)
[02:45:40.878] Timer tick, asking Buildbucket for the build status
[02:45:41.120] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:41.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:5:0) after 1m0s
[02:46:41.147] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:5:0)
[02:46:41.147] Timer tick, asking Buildbucket for the build status
[02:46:41.457] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:41.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:6:0) after 1m0s
[02:47:41.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:6:0)
[02:47:41.591] Timer tick, asking Buildbucket for the build status
[02:47:41.676] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:41.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:7:0) after 1m0s
[02:48:41.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:7:0)
[02:48:41.696] Timer tick, asking Buildbucket for the build status
[02:48:41.764] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:41.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:8:0) after 1m0s
[02:49:41.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:8:0)
[02:49:41.757] Timer tick, asking Buildbucket for the build status
[02:49:42.009] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:42.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:9:0) after 1m0s
[02:50:42.032] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:9:0)
[02:50:42.032] Timer tick, asking Buildbucket for the build status
[02:50:42.119] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:42.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:10:0) after 1m0s
[02:51:42.141] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:10:0)
[02:51:42.141] Timer tick, asking Buildbucket for the build status
[02:51:42.247] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:42.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:11:0) after 1m0s
[02:52:42.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:11:0)
[02:52:42.257] Timer tick, asking Buildbucket for the build status
[02:52:42.339] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:42.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:12:0) after 1m0s
[02:53:42.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:12:0)
[02:53:42.363] Timer tick, asking Buildbucket for the build status
[02:53:42.965] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:42.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:13:0) after 1m0s
[02:54:43.033] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:13:0)
[02:54:43.033] Timer tick, asking Buildbucket for the build status
[02:54:43.210] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:43.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:14:0) after 1m0s
[02:55:43.225] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:14:0)
[02:55:43.225] Timer tick, asking Buildbucket for the build status
[02:55:43.410] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:43.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:15:0) after 1m0s
[02:56:43.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:15:0)
[02:56:43.439] Timer tick, asking Buildbucket for the build status
[02:56:43.523] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:43.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:16:0) after 1m0s
[02:57:43.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:16:0)
[02:57:43.616] Timer tick, asking Buildbucket for the build status
[02:57:43.705] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:43.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:17:0) after 1m0s
[02:58:43.723] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:17:0)
[02:58:43.723] Timer tick, asking Buildbucket for the build status
[02:58:43.796] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:43.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:18:0) after 1m0s
[02:59:43.872] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:18:0)
[02:59:43.872] Timer tick, asking Buildbucket for the build status
[02:59:44.070] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:44.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:19:0) after 1m0s
[03:00:44.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:19:0)
[03:00:44.090] Timer tick, asking Buildbucket for the build status
[03:00:44.239] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:44.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:20:0) after 1m0s
[03:01:44.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:20:0)
[03:01:44.215] Timer tick, asking Buildbucket for the build status
[03:01:44.284] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:44.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:21:0) after 1m0s
[03:02:44.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:21:0)
[03:02:44.303] Timer tick, asking Buildbucket for the build status
[03:02:44.420] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:44.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:22:0) after 1m0s
[03:03:44.501] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:22:0)
[03:03:44.501] Timer tick, asking Buildbucket for the build status
[03:03:44.599] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:44.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:23:0) after 1m0s
[03:04:44.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:23:0)
[03:04:44.616] Timer tick, asking Buildbucket for the build status
[03:04:44.709] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:44.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:24:0) after 1m0s
[03:05:44.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:24:0)
[03:05:44.731] Timer tick, asking Buildbucket for the build status
[03:05:44.769] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:44.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:25:0) after 1m0s
[03:06:44.791] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:25:0)
[03:06:44.791] Timer tick, asking Buildbucket for the build status
[03:06:45.035] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:45.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:26:0) after 1m0s
[03:07:45.058] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:26:0)
[03:07:45.058] Timer tick, asking Buildbucket for the build status
[03:07:45.199] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:45.199] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:27:0) after 1m0s
[03:08:45.220] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:27:0)
[03:08:45.220] Timer tick, asking Buildbucket for the build status
[03:08:45.438] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:45.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:28:0) after 1m0s
[03:09:45.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:28:0)
[03:09:45.511] Timer tick, asking Buildbucket for the build status
[03:09:45.679] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:45.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:29:0) after 1m0s
[03:10:45.858] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:29:0)
[03:10:45.858] Timer tick, asking Buildbucket for the build status
[03:10:46.082] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:46.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:30:0) after 1m0s
[03:11:46.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:30:0)
[03:11:46.075] Timer tick, asking Buildbucket for the build status
[03:11:46.152] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:46.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:31:0) after 1m0s
[03:12:46.181] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:31:0)
[03:12:46.181] Timer tick, asking Buildbucket for the build status
[03:12:46.348] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:46.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:32:0) after 1m0s
[03:13:46.349] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:32:0)
[03:13:46.349] Timer tick, asking Buildbucket for the build status
[03:13:46.410] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:46.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:33:0) after 1m0s
[03:14:46.461] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:33:0)
[03:14:46.461] Timer tick, asking Buildbucket for the build status
[03:14:46.527] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:46.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:34:0) after 1m0s
[03:15:46.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:34:0)
[03:15:46.551] Timer tick, asking Buildbucket for the build status
[03:15:46.677] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:46.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:35:0) after 1m0s
[03:16:46.750] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:35:0)
[03:16:46.750] Timer tick, asking Buildbucket for the build status
[03:16:47.201] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:47.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:36:0) after 1m0s
[03:17:47.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:36:0)
[03:17:47.470] Timer tick, asking Buildbucket for the build status
[03:17:47.510] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:47.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:37:0) after 1m0s
[03:18:47.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:37:0)
[03:18:47.597] Timer tick, asking Buildbucket for the build status
[03:18:47.849] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:47.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:38:0) after 1m0s
[03:19:47.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:38:0)
[03:19:47.904] Timer tick, asking Buildbucket for the build status
[03:19:47.990] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:47.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:39:0) after 1m0s
[03:20:47.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:39:0)
[03:20:47.965] Timer tick, asking Buildbucket for the build status
[03:20:48.026] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:48.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:40:0) after 1m0s
[03:21:48.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:40:0)
[03:21:48.103] Timer tick, asking Buildbucket for the build status
[03:21:48.259] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:48.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:41:0) after 1m0s
[03:22:48.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:41:0)
[03:22:48.361] Timer tick, asking Buildbucket for the build status
[03:22:48.425] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:48.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:42:0) after 1m0s
[03:23:48.447] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:42:0)
[03:23:48.447] Timer tick, asking Buildbucket for the build status
[03:23:48.513] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:48.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:43:0) after 1m0s
[03:24:48.602] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:43:0)
[03:24:48.602] Timer tick, asking Buildbucket for the build status
[03:24:48.676] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:48.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:44:0) after 1m0s
[03:25:48.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:44:0)
[03:25:48.720] Timer tick, asking Buildbucket for the build status
[03:25:48.913] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:48.913] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:45:0) after 1m0s
[03:26:48.935] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:45:0)
[03:26:48.935] Timer tick, asking Buildbucket for the build status
[03:26:49.031] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:49.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:46:0) after 1m0s
[03:27:49.160] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:46:0)
[03:27:49.160] Timer tick, asking Buildbucket for the build status
[03:27:49.693] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:49.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:47:0) after 1m0s
[03:28:49.761] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:47:0)
[03:28:49.761] Timer tick, asking Buildbucket for the build status
[03:28:49.884] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:49.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:48:0) after 1m0s
[03:29:49.885] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:48:0)
[03:29:49.885] Timer tick, asking Buildbucket for the build status
[03:29:50.105] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:50.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:49:0) after 1m0s
[03:30:50.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:49:0)
[03:30:50.427] Timer tick, asking Buildbucket for the build status
[03:30:50.561] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:50.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:50:0) after 1m0s
[03:31:50.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:50:0)
[03:31:50.736] Timer tick, asking Buildbucket for the build status
[03:31:50.854] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:50.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:51:0) after 1m0s
[03:32:50.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:51:0)
[03:32:50.955] Timer tick, asking Buildbucket for the build status
[03:32:51.104] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:51.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:52:0) after 1m0s
[03:33:51.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:52:0)
[03:33:51.159] Timer tick, asking Buildbucket for the build status
[03:33:51.228] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:51.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:53:0) after 1m0s
[03:34:51.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:53:0)
[03:34:51.301] Timer tick, asking Buildbucket for the build status
[03:34:51.430] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:51.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:54:0) after 1m0s
[03:35:51.520] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:54:0)
[03:35:51.520] Timer tick, asking Buildbucket for the build status
[03:35:51.602] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:51.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:55:0) after 1m0s
[03:36:51.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:55:0)
[03:36:51.651] Timer tick, asking Buildbucket for the build status
[03:36:51.807] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:51.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:56:0) after 1m0s
[03:37:51.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:56:0)
[03:37:51.828] Timer tick, asking Buildbucket for the build status
[03:37:51.896] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:51.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:57:0) after 1m0s
[03:38:51.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:57:0)
[03:38:51.966] Timer tick, asking Buildbucket for the build status
[03:38:52.057] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:52.057] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:58:0) after 1m0s
[03:39:52.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:58:0)
[03:39:52.087] Timer tick, asking Buildbucket for the build status
[03:39:52.143] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:52.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:59:0) after 1m0s
[03:40:52.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:59:0)
[03:40:52.249] Timer tick, asking Buildbucket for the build status
[03:40:52.622] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:52.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:60:0) after 1m0s
[03:41:52.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:60:0)
[03:41:52.755] Timer tick, asking Buildbucket for the build status
[03:41:52.838] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:52.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:61:0) after 1m0s
[03:42:52.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:61:0)
[03:42:52.956] Timer tick, asking Buildbucket for the build status
[03:42:53.105] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:53.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:62:0) after 1m0s
[03:43:53.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:62:0)
[03:43:53.144] Timer tick, asking Buildbucket for the build status
[03:43:53.307] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:53.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:63:0) after 1m0s
[03:44:53.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:63:0)
[03:44:53.382] Timer tick, asking Buildbucket for the build status
[03:44:53.471] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:53.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:64:0) after 1m0s
[03:45:53.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:64:0)
[03:45:53.569] Timer tick, asking Buildbucket for the build status
[03:45:53.659] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:53.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:65:0) after 1m0s
[03:46:53.721] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:65:0)
[03:46:53.721] Timer tick, asking Buildbucket for the build status
[03:46:53.859] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:53.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:66:0) after 1m0s
[03:47:53.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:66:0)
[03:47:53.879] Timer tick, asking Buildbucket for the build status
[03:47:53.924] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:53.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:67:0) after 1m0s
[03:48:53.958] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:67:0)
[03:48:53.958] Timer tick, asking Buildbucket for the build status
[03:48:54.244] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:54.244] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:68:0) after 1m0s
[03:49:54.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:68:0)
[03:49:54.316] Timer tick, asking Buildbucket for the build status
[03:49:54.474] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:54.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:69:0) after 1m0s
[03:50:54.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:69:0)
[03:50:54.514] Timer tick, asking Buildbucket for the build status
[03:50:54.575] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:54.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:70:0) after 1m0s
[03:51:54.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:70:0)
[03:51:54.576] Timer tick, asking Buildbucket for the build status
[03:51:54.793] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:54.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:71:0) after 1m0s
[03:52:54.877] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:71:0)
[03:52:54.877] Timer tick, asking Buildbucket for the build status
[03:52:54.994] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:54.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:72:0) after 1m0s
[03:53:55.022] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:72:0)
[03:53:55.022] Timer tick, asking Buildbucket for the build status
[03:53:55.105] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:55.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:73:0) after 1m0s
[03:54:55.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:73:0)
[03:54:55.088] Timer tick, asking Buildbucket for the build status
[03:54:55.171] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:55.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:74:0) after 1m0s
[03:55:55.192] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:74:0)
[03:55:55.192] Timer tick, asking Buildbucket for the build status
[03:55:55.265] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:55.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:75:0) after 1m0s
[03:56:55.341] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:75:0)
[03:56:55.341] Timer tick, asking Buildbucket for the build status
[03:56:55.505] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:55.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:76:0) after 1m0s
[03:57:55.539] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:76:0)
[03:57:55.539] Timer tick, asking Buildbucket for the build status
[03:57:55.679] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:55.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:77:0) after 1m0s
[03:58:55.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:77:0)
[03:58:55.715] Timer tick, asking Buildbucket for the build status
[03:58:55.762] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:55.762] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:78:0) after 1m0s
[03:59:55.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:78:0)
[03:59:55.745] Timer tick, asking Buildbucket for the build status
[03:59:55.872] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:55.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:79:0) after 1m0s
[04:00:55.893] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:79:0)
[04:00:55.893] Timer tick, asking Buildbucket for the build status
[04:00:55.973] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:55.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:80:0) after 1m0s
[04:01:56.011] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:80:0)
[04:01:56.011] Timer tick, asking Buildbucket for the build status
[04:01:56.107] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:56.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:81:0) after 1m0s
[04:02:56.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:81:0)
[04:02:56.138] Timer tick, asking Buildbucket for the build status
[04:02:56.338] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:56.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:82:0) after 1m0s
[04:03:56.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:82:0)
[04:03:56.379] Timer tick, asking Buildbucket for the build status
[04:03:56.568] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:56.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:83:0) after 1m0s
[04:04:56.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:83:0)
[04:04:56.612] Timer tick, asking Buildbucket for the build status
[04:04:56.769] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:56.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:84:0) after 1m0s
[04:05:56.788] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:84:0)
[04:05:56.788] Timer tick, asking Buildbucket for the build status
[04:05:56.870] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:56.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:85:0) after 1m0s
[04:06:56.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:85:0)
[04:06:56.920] Timer tick, asking Buildbucket for the build status
[04:06:57.107] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:57.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:86:0) after 1m0s
[04:07:57.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:86:0)
[04:07:57.171] Timer tick, asking Buildbucket for the build status
[04:07:57.327] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:57.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:87:0) after 1m0s
[04:08:57.347] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:87:0)
[04:08:57.347] Timer tick, asking Buildbucket for the build status
[04:08:57.549] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:57.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:88:0) after 1m0s
[04:09:57.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:88:0)
[04:09:57.572] Timer tick, asking Buildbucket for the build status
[04:09:57.628] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:57.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:89:0) after 1m0s
[04:10:57.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:89:0)
[04:10:57.651] Timer tick, asking Buildbucket for the build status
[04:10:57.767] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:57.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:90:0) after 1m0s
[04:11:57.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:90:0)
[04:11:57.808] Timer tick, asking Buildbucket for the build status
[04:11:57.911] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:57.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:91:0) after 1m0s
[04:12:57.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:91:0)
[04:12:57.933] Timer tick, asking Buildbucket for the build status
[04:12:58.017] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:58.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:92:0) after 1m0s
[04:13:58.041] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:92:0)
[04:13:58.041] Timer tick, asking Buildbucket for the build status
[04:13:58.165] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:58.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:93:0) after 1m0s
[04:14:58.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:93:0)
[04:14:58.214] Timer tick, asking Buildbucket for the build status
[04:14:58.256] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:58.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:94:0) after 1m0s
[04:15:58.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:94:0)
[04:15:58.289] Timer tick, asking Buildbucket for the build status
[04:15:58.457] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:58.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:95:0) after 1m0s
[04:16:58.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:95:0)
[04:16:58.483] Timer tick, asking Buildbucket for the build status
[04:16:58.571] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:58.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:96:0) after 1m0s
[04:17:58.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:96:0)
[04:17:58.595] Timer tick, asking Buildbucket for the build status
[04:17:58.685] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:58.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:97:0) after 1m0s
[04:18:58.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:97:0)
[04:18:58.738] Timer tick, asking Buildbucket for the build status
[04:18:58.830] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:58.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:98:0) after 1m0s
[04:19:58.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:98:0)
[04:19:58.867] Timer tick, asking Buildbucket for the build status
[04:19:58.996] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:58.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:99:0) after 1m0s
[04:20:59.151] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:99:0)
[04:20:59.151] Timer tick, asking Buildbucket for the build status
[04:20:59.213] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:59.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:100:0) after 1m0s
[04:21:59.381] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:100:0)
[04:21:59.381] Timer tick, asking Buildbucket for the build status
[04:21:59.432] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:59.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:101:0) after 1m0s
[04:22:59.474] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:101:0)
[04:22:59.474] Timer tick, asking Buildbucket for the build status
[04:22:59.550] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:59.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:102:0) after 1m0s
[04:23:59.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:102:0)
[04:23:59.921] Timer tick, asking Buildbucket for the build status
[04:24:00.033] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:00.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:103:0) after 1m0s
[04:25:01.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:103:0)
[04:25:01.107] Timer tick, asking Buildbucket for the build status
[04:25:02.652] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:02.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:104:0) after 1m0s
[04:26:02.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:104:0)
[04:26:02.726] Timer tick, asking Buildbucket for the build status
[04:26:02.832] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:02.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:105:0) after 1m0s
[04:27:02.867] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:105:0)
[04:27:02.867] Timer tick, asking Buildbucket for the build status
[04:27:03.056] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:03.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:106:0) after 1m0s
[04:28:03.081] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:106:0)
[04:28:03.081] Timer tick, asking Buildbucket for the build status
[04:28:03.154] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:03.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:107:0) after 1m0s
[04:29:03.150] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:107:0)
[04:29:03.150] Timer tick, asking Buildbucket for the build status
[04:29:03.252] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:03.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:108:0) after 1m0s
[04:30:03.327] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:108:0)
[04:30:03.327] Timer tick, asking Buildbucket for the build status
[04:30:04.989] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:04.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:109:0) after 1m0s
[04:31:05.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:109:0)
[04:31:05.044] Timer tick, asking Buildbucket for the build status
[04:31:05.239] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:05.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:110:0) after 1m0s
[04:32:05.364] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:110:0)
[04:32:05.364] Timer tick, asking Buildbucket for the build status
[04:32:05.752] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:05.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:111:0) after 1m0s
[04:33:05.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:111:0)
[04:33:05.776] Timer tick, asking Buildbucket for the build status
[04:33:05.948] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:05.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:112:0) after 1m0s
[04:34:05.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:112:0)
[04:34:05.972] Timer tick, asking Buildbucket for the build status
[04:34:06.395] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:06.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:113:0) after 1m0s
[04:35:06.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:113:0)
[04:35:06.431] Timer tick, asking Buildbucket for the build status
[04:35:06.581] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:06.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:114:0) after 1m0s
[04:36:06.655] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:114:0)
[04:36:06.655] Timer tick, asking Buildbucket for the build status
[04:36:08.138] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:08.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:115:0) after 1m0s
[04:37:08.137] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:115:0)
[04:37:08.137] Timer tick, asking Buildbucket for the build status
[04:37:08.214] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:08.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:116:0) after 1m0s
[04:38:08.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:116:0)
[04:38:08.277] Timer tick, asking Buildbucket for the build status
[04:38:08.543] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:08.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:117:0) after 1m0s
[04:39:08.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:117:0)
[04:39:08.609] Timer tick, asking Buildbucket for the build status
[04:39:08.723] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:08.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:118:0) after 1m0s
[04:40:08.748] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:118:0)
[04:40:08.748] Timer tick, asking Buildbucket for the build status
[04:40:08.793] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:08.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:119:0) after 1m0s
[04:41:08.841] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:119:0)
[04:41:08.841] Timer tick, asking Buildbucket for the build status
[04:41:08.918] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:08.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:120:0) after 1m0s
[04:42:08.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:120:0)
[04:42:08.978] Timer tick, asking Buildbucket for the build status
[04:42:09.340] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:09.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:121:0) after 1m0s
[04:43:09.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:121:0)
[04:43:09.382] Timer tick, asking Buildbucket for the build status
[04:43:09.459] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:09.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:122:0) after 1m0s
[04:44:09.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:122:0)
[04:44:09.551] Timer tick, asking Buildbucket for the build status
[04:44:09.759] Build 8912342354143569520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:09.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077772416375494240:123:0) after 1m0s
[04:44:24.315] Received PubSub notification, asking Buildbucket for the build status
[04:44:24.571] Build 8912342354143569520: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[04:44:24.571] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558932263385324",
"created_by": "project:fuchsia",
"created_ts": "1558925020041519",
"failure_reason": "INFRA_FAILURE",
"id": "8912342354143569520",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"cfe08bc7d68712d8bdb2c205981bbbf15968a19e\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"recipe infra failure: Infra Failure: Step('buildbucket.run.collect') (timeout)\"}, \"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-6nss\", \"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\": \"cfe08bc7d68712d8bdb2c205981bbbf15968a19e\", \"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-6nss\"], \"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\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"recipe infra failure: Infra Failure: Step('buildbucket.run.collect') (timeout)\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1558925040371602",
"status": "COMPLETED",
"status_changed_ts": "1558925041111739",
"tags": [
"builder:clang",
"buildset:commit/git/cfe08bc7d68712d8bdb2c205981bbbf15968a19e",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/cfe08bc7d68712d8bdb2c205981bbbf15968a19e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077772416375494240",
"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/8912342354143569520/+/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:450ffd9e75efcd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558932263491471",
"url": "https://ci.chromium.org/b/8912342354143569520",
"utcnow_ts": "1558932264538629"
}
}
[04:44:24.571] Invocation finished in 2h0m46.041901958s with status FAILED