[01:22:48.369] New invocation is queued and will start shortly
[01:22:49.452] Starting the invocation (attempt 1)
[01:22:49.499] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:22:49.499] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:22:49.499] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9083575708002559856",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"88f4054f48c56b816a555569ccd6e94c7072ab26\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9083575708002559856",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/88f4054f48c56b816a555569ccd6e94c7072ab26",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/88f4054f48c56b816a555569ccd6e94c7072ab26",
"gitiles_ref:refs/heads/master"
]
}
[01:22:50.951] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1553390569656244",
"id": "8918145645990654832",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"88f4054f48c56b816a555569ccd6e94c7072ab26\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553390570767650",
"tags": [
"builder:clang",
"buildset:commit/git/88f4054f48c56b816a555569ccd6e94c7072ab26",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/88f4054f48c56b816a555569ccd6e94c7072ab26",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083575708002559856",
"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/8918145645990654832/+/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:43c61c929cba2610",
"user_agent:luci-scheduler"
],
"updated_ts": "1553390570767732",
"url": "https://ci.chromium.org/b/8918145645990654832",
"utcnow_ts": "1553390570939405"
}
}
[01:22:50.951] Task URL: https://ci.chromium.org/b/8918145645990654832
[01:22:50.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:2:0) after 1m0s
[01:23:05.174] Received PubSub notification, asking Buildbucket for the build status
[01:23:05.611] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:50.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:2:0)
[01:23:50.972] Timer tick, asking Buildbucket for the build status
[01:23:51.060] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:51.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:4:0) after 1m0s
[01:24:51.067] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:4:0)
[01:24:51.067] Timer tick, asking Buildbucket for the build status
[01:24:51.140] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:51.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:5:0) after 1m0s
[01:25:51.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:5:0)
[01:25:51.127] Timer tick, asking Buildbucket for the build status
[01:25:51.286] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:51.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:6:0) after 1m0s
[01:26:51.262] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:6:0)
[01:26:51.262] Timer tick, asking Buildbucket for the build status
[01:26:51.350] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:51.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:7:0) after 1m0s
[01:27:51.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:7:0)
[01:27:51.336] Timer tick, asking Buildbucket for the build status
[01:27:51.596] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:51.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:8:0) after 1m0s
[01:28:51.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:8:0)
[01:28:51.615] Timer tick, asking Buildbucket for the build status
[01:28:51.721] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:51.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:9:0) after 1m0s
[01:29:51.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:9:0)
[01:29:51.701] Timer tick, asking Buildbucket for the build status
[01:29:51.976] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:51.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:10:0) after 1m0s
[01:30:52.051] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:10:0)
[01:30:52.051] Timer tick, asking Buildbucket for the build status
[01:30:52.126] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:52.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:11:0) after 1m0s
[01:31:52.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:11:0)
[01:31:52.164] Timer tick, asking Buildbucket for the build status
[01:31:52.223] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:52.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:12:0) after 1m0s
[01:32:52.254] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:12:0)
[01:32:52.254] Timer tick, asking Buildbucket for the build status
[01:32:52.468] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:52.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:13:0) after 1m0s
[01:33:52.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:13:0)
[01:33:52.450] Timer tick, asking Buildbucket for the build status
[01:33:52.529] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:52.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:14:0) after 1m0s
[01:34:52.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:14:0)
[01:34:52.551] Timer tick, asking Buildbucket for the build status
[01:34:52.614] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:52.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:15:0) after 1m0s
[01:35:52.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:15:0)
[01:35:52.625] Timer tick, asking Buildbucket for the build status
[01:35:52.760] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:52.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:16:0) after 1m0s
[01:36:52.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:16:0)
[01:36:52.775] Timer tick, asking Buildbucket for the build status
[01:36:52.838] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:52.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:17:0) after 1m0s
[01:37:52.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:17:0)
[01:37:52.810] Timer tick, asking Buildbucket for the build status
[01:37:52.883] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:52.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:18:0) after 1m0s
[01:38:52.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:18:0)
[01:38:52.949] Timer tick, asking Buildbucket for the build status
[01:38:53.014] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:53.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:19:0) after 1m0s
[01:39:53.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:19:0)
[01:39:53.000] Timer tick, asking Buildbucket for the build status
[01:39:53.106] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:53.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:20:0) after 1m0s
[01:40:53.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:20:0)
[01:40:53.129] Timer tick, asking Buildbucket for the build status
[01:40:53.271] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:53.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:21:0) after 1m0s
[01:41:53.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:21:0)
[01:41:53.249] Timer tick, asking Buildbucket for the build status
[01:41:53.316] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:53.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:22:0) after 1m0s
[01:42:53.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:22:0)
[01:42:53.336] Timer tick, asking Buildbucket for the build status
[01:42:53.407] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:53.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:23:0) after 1m0s
[01:43:53.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:23:0)
[01:43:53.434] Timer tick, asking Buildbucket for the build status
[01:43:53.744] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:53.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:24:0) after 1m0s
[01:44:53.750] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:24:0)
[01:44:53.750] Timer tick, asking Buildbucket for the build status
[01:44:53.912] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:53.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:25:0) after 1m0s
[01:45:53.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:25:0)
[01:45:53.930] Timer tick, asking Buildbucket for the build status
[01:45:53.991] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:53.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:26:0) after 1m0s
[01:46:54.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:26:0)
[01:46:54.031] Timer tick, asking Buildbucket for the build status
[01:46:54.147] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:54.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:27:0) after 1m0s
[01:47:54.146] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:27:0)
[01:47:54.146] Timer tick, asking Buildbucket for the build status
[01:47:54.210] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:54.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:28:0) after 1m0s
[01:48:54.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:28:0)
[01:48:54.241] Timer tick, asking Buildbucket for the build status
[01:48:54.304] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:54.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:29:0) after 1m0s
[01:49:54.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:29:0)
[01:49:54.299] Timer tick, asking Buildbucket for the build status
[01:49:54.397] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:54.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:30:0) after 1m0s
[01:50:54.418] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:30:0)
[01:50:54.418] Timer tick, asking Buildbucket for the build status
[01:50:54.482] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:54.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:31:0) after 1m0s
[01:51:54.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:31:0)
[01:51:54.496] Timer tick, asking Buildbucket for the build status
[01:51:54.550] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:54.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:32:0) after 1m0s
[01:52:54.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:32:0)
[01:52:54.581] Timer tick, asking Buildbucket for the build status
[01:52:54.794] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:54.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:33:0) after 1m0s
[01:53:54.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:33:0)
[01:53:54.799] Timer tick, asking Buildbucket for the build status
[01:53:54.905] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:54.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:34:0) after 1m0s
[01:54:54.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:34:0)
[01:54:54.905] Timer tick, asking Buildbucket for the build status
[01:54:54.992] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:54.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:35:0) after 1m0s
[01:55:55.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:35:0)
[01:55:55.002] Timer tick, asking Buildbucket for the build status
[01:55:55.068] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:55.068] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:36:0) after 1m0s
[01:56:55.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:36:0)
[01:56:55.090] Timer tick, asking Buildbucket for the build status
[01:56:56.121] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:56.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:37:0) after 1m0s
[01:57:56.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:37:0)
[01:57:56.153] Timer tick, asking Buildbucket for the build status
[01:57:56.291] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:56.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:38:0) after 1m0s
[01:58:56.313] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:38:0)
[01:58:56.313] Timer tick, asking Buildbucket for the build status
[01:58:56.642] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:56.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:39:0) after 1m0s
[01:59:56.624] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:39:0)
[01:59:56.624] Timer tick, asking Buildbucket for the build status
[01:59:56.827] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:56.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:40:0) after 1m0s
[02:00:56.846] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:40:0)
[02:00:56.846] Timer tick, asking Buildbucket for the build status
[02:00:56.947] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:56.947] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:41:0) after 1m0s
[02:01:56.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:41:0)
[02:01:56.967] Timer tick, asking Buildbucket for the build status
[02:01:57.138] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:57.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:42:0) after 1m0s
[02:02:57.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:42:0)
[02:02:57.122] Timer tick, asking Buildbucket for the build status
[02:02:57.227] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:57.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:43:0) after 1m0s
[02:03:57.255] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:43:0)
[02:03:57.255] Timer tick, asking Buildbucket for the build status
[02:03:57.474] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:57.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:44:0) after 1m0s
[02:04:57.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:44:0)
[02:04:57.497] Timer tick, asking Buildbucket for the build status
[02:04:57.556] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:57.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:45:0) after 1m0s
[02:05:57.562] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:45:0)
[02:05:57.562] Timer tick, asking Buildbucket for the build status
[02:05:57.693] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:57.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:46:0) after 1m0s
[02:06:57.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:46:0)
[02:06:57.774] Timer tick, asking Buildbucket for the build status
[02:06:57.889] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:57.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:47:0) after 1m0s
[02:07:57.864] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:47:0)
[02:07:57.864] Timer tick, asking Buildbucket for the build status
[02:07:57.936] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:57.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:48:0) after 1m0s
[02:08:57.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:48:0)
[02:08:57.956] Timer tick, asking Buildbucket for the build status
[02:08:58.141] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:58.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:49:0) after 1m0s
[02:09:58.176] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:49:0)
[02:09:58.177] Timer tick, asking Buildbucket for the build status
[02:09:58.384] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:58.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:50:0) after 1m0s
[02:10:58.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:50:0)
[02:10:58.406] Timer tick, asking Buildbucket for the build status
[02:10:58.478] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:58.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:51:0) after 1m0s
[02:11:58.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:51:0)
[02:11:58.453] Timer tick, asking Buildbucket for the build status
[02:11:58.579] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:58.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:52:0) after 1m0s
[02:12:58.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:52:0)
[02:12:58.597] Timer tick, asking Buildbucket for the build status
[02:12:58.674] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:58.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:53:0) after 1m0s
[02:13:58.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:53:0)
[02:13:58.673] Timer tick, asking Buildbucket for the build status
[02:13:58.733] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:58.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:54:0) after 1m0s
[02:14:58.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:54:0)
[02:14:58.783] Timer tick, asking Buildbucket for the build status
[02:14:59.052] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:59.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:55:0) after 1m0s
[02:15:59.055] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:55:0)
[02:15:59.055] Timer tick, asking Buildbucket for the build status
[02:15:59.213] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:59.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:56:0) after 1m0s
[02:16:59.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:56:0)
[02:16:59.247] Timer tick, asking Buildbucket for the build status
[02:16:59.402] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:59.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:57:0) after 1m0s
[02:17:59.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:57:0)
[02:17:59.428] Timer tick, asking Buildbucket for the build status
[02:17:59.484] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:59.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:58:0) after 1m0s
[02:18:59.495] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:58:0)
[02:18:59.495] Timer tick, asking Buildbucket for the build status
[02:18:59.582] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:59.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:59:0) after 1m0s
[02:19:59.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:59:0)
[02:19:59.606] Timer tick, asking Buildbucket for the build status
[02:19:59.831] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:59.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:60:0) after 1m0s
[02:20:59.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:60:0)
[02:20:59.804] Timer tick, asking Buildbucket for the build status
[02:20:59.877] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:59.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:61:0) after 1m0s
[02:21:59.900] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:61:0)
[02:21:59.900] Timer tick, asking Buildbucket for the build status
[02:21:59.976] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:59.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:62:0) after 1m0s
[02:23:00.054] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:62:0)
[02:23:00.054] Timer tick, asking Buildbucket for the build status
[02:23:00.158] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:00.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:63:0) after 1m0s
[02:24:00.179] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:63:0)
[02:24:00.179] Timer tick, asking Buildbucket for the build status
[02:24:00.252] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:00.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:64:0) after 1m0s
[02:25:00.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:64:0)
[02:25:00.257] Timer tick, asking Buildbucket for the build status
[02:25:00.317] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:00.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:65:0) after 1m0s
[02:26:00.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:65:0)
[02:26:00.336] Timer tick, asking Buildbucket for the build status
[02:26:00.408] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:00.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:66:0) after 1m0s
[02:27:00.408] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:66:0)
[02:27:00.408] Timer tick, asking Buildbucket for the build status
[02:27:00.499] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:00.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:67:0) after 1m0s
[02:28:00.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:67:0)
[02:28:00.486] Timer tick, asking Buildbucket for the build status
[02:28:00.907] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:00.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:68:0) after 1m0s
[02:29:00.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:68:0)
[02:29:00.987] Timer tick, asking Buildbucket for the build status
[02:29:01.186] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:01.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:69:0) after 1m0s
[02:30:01.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:69:0)
[02:30:01.208] Timer tick, asking Buildbucket for the build status
[02:30:01.386] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:01.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:70:0) after 1m0s
[02:31:01.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:70:0)
[02:31:01.406] Timer tick, asking Buildbucket for the build status
[02:31:01.464] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:01.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:71:0) after 1m0s
[02:32:01.452] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:71:0)
[02:32:01.452] Timer tick, asking Buildbucket for the build status
[02:32:01.615] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:01.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:72:0) after 1m0s
[02:33:01.638] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:72:0)
[02:33:01.638] Timer tick, asking Buildbucket for the build status
[02:33:01.726] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:01.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:73:0) after 1m0s
[02:34:01.739] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:73:0)
[02:34:01.739] Timer tick, asking Buildbucket for the build status
[02:34:01.874] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:01.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:74:0) after 1m0s
[02:35:01.904] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:74:0)
[02:35:01.904] Timer tick, asking Buildbucket for the build status
[02:35:02.113] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:02.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:75:0) after 1m0s
[02:36:02.130] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:75:0)
[02:36:02.130] Timer tick, asking Buildbucket for the build status
[02:36:02.188] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:02.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:76:0) after 1m0s
[02:37:02.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:76:0)
[02:37:02.189] Timer tick, asking Buildbucket for the build status
[02:37:02.260] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:02.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:77:0) after 1m0s
[02:38:02.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:77:0)
[02:38:02.282] Timer tick, asking Buildbucket for the build status
[02:38:02.335] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:02.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:78:0) after 1m0s
[02:39:02.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:78:0)
[02:39:02.362] Timer tick, asking Buildbucket for the build status
[02:39:02.522] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:02.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:79:0) after 1m0s
[02:40:02.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:79:0)
[02:40:02.506] Timer tick, asking Buildbucket for the build status
[02:40:02.886] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:02.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:80:0) after 1m0s
[02:41:02.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:80:0)
[02:41:02.873] Timer tick, asking Buildbucket for the build status
[02:41:03.054] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:03.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:81:0) after 1m0s
[02:42:03.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:81:0)
[02:42:03.123] Timer tick, asking Buildbucket for the build status
[02:42:03.223] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:03.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:82:0) after 1m0s
[02:43:03.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:82:0)
[02:43:03.197] Timer tick, asking Buildbucket for the build status
[02:43:03.275] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:03.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:83:0) after 1m0s
[02:44:03.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:83:0)
[02:44:03.273] Timer tick, asking Buildbucket for the build status
[02:44:03.344] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:03.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:84:0) after 1m0s
[02:45:03.329] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:84:0)
[02:45:03.329] Timer tick, asking Buildbucket for the build status
[02:45:03.620] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:03.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:85:0) after 1m0s
[02:46:03.621] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:85:0)
[02:46:03.621] Timer tick, asking Buildbucket for the build status
[02:46:03.687] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:03.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:86:0) after 1m0s
[02:47:03.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:86:0)
[02:47:03.695] Timer tick, asking Buildbucket for the build status
[02:47:03.888] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:03.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:87:0) after 1m0s
[02:48:03.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:87:0)
[02:48:03.910] Timer tick, asking Buildbucket for the build status
[02:48:03.981] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:03.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:88:0) after 1m0s
[02:49:03.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:88:0)
[02:49:03.984] Timer tick, asking Buildbucket for the build status
[02:49:04.061] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:04.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:89:0) after 1m0s
[02:50:04.069] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:89:0)
[02:50:04.069] Timer tick, asking Buildbucket for the build status
[02:50:04.217] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:04.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:90:0) after 1m0s
[02:51:04.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:90:0)
[02:51:04.231] Timer tick, asking Buildbucket for the build status
[02:51:04.382] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:04.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:91:0) after 1m0s
[02:52:04.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:91:0)
[02:52:04.493] Timer tick, asking Buildbucket for the build status
[02:52:04.679] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:04.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:92:0) after 1m0s
[02:53:04.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:92:0)
[02:53:04.715] Timer tick, asking Buildbucket for the build status
[02:53:04.817] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:04.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:93:0) after 1m0s
[02:54:04.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:93:0)
[02:54:04.832] Timer tick, asking Buildbucket for the build status
[02:54:04.963] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:04.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:94:0) after 1m0s
[02:55:04.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:94:0)
[02:55:04.968] Timer tick, asking Buildbucket for the build status
[02:55:05.025] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:05.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:95:0) after 1m0s
[02:56:05.082] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:95:0)
[02:56:05.082] Timer tick, asking Buildbucket for the build status
[02:56:05.189] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:05.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:96:0) after 1m0s
[02:57:05.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:96:0)
[02:57:05.214] Timer tick, asking Buildbucket for the build status
[02:57:05.276] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:05.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:97:0) after 1m0s
[02:58:05.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:97:0)
[02:58:05.289] Timer tick, asking Buildbucket for the build status
[02:58:05.450] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:05.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:98:0) after 1m0s
[02:59:05.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:98:0)
[02:59:05.432] Timer tick, asking Buildbucket for the build status
[02:59:05.582] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:05.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:99:0) after 1m0s
[03:00:05.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:99:0)
[03:00:05.611] Timer tick, asking Buildbucket for the build status
[03:00:05.777] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:05.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:100:0) after 1m0s
[03:01:05.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:100:0)
[03:01:05.779] Timer tick, asking Buildbucket for the build status
[03:01:06.016] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:06.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:101:0) after 1m0s
[03:02:06.056] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:101:0)
[03:02:06.056] Timer tick, asking Buildbucket for the build status
[03:02:06.184] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:06.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:102:0) after 1m0s
[03:03:06.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:102:0)
[03:03:06.213] Timer tick, asking Buildbucket for the build status
[03:03:06.313] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:06.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:103:0) after 1m0s
[03:04:06.633] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:103:0)
[03:04:06.633] Timer tick, asking Buildbucket for the build status
[03:04:07.218] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:07.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:104:0) after 1m0s
[03:05:07.279] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:104:0)
[03:05:07.279] Timer tick, asking Buildbucket for the build status
[03:05:07.334] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:07.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:105:0) after 1m0s
[03:06:07.358] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:105:0)
[03:06:07.358] Timer tick, asking Buildbucket for the build status
[03:06:08.181] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:08.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:106:0) after 1m0s
[03:07:08.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:106:0)
[03:07:08.207] Timer tick, asking Buildbucket for the build status
[03:07:08.285] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:08.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:107:0) after 1m0s
[03:08:08.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:107:0)
[03:08:08.311] Timer tick, asking Buildbucket for the build status
[03:08:08.371] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:08.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:108:0) after 1m0s
[03:09:08.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:108:0)
[03:09:08.390] Timer tick, asking Buildbucket for the build status
[03:09:08.506] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:08.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:109:0) after 1m0s
[03:10:08.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:109:0)
[03:10:08.499] Timer tick, asking Buildbucket for the build status
[03:10:08.562] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:08.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:110:0) after 1m0s
[03:11:08.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:110:0)
[03:11:08.582] Timer tick, asking Buildbucket for the build status
[03:11:08.642] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:08.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:111:0) after 1m0s
[03:12:08.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:111:0)
[03:12:08.671] Timer tick, asking Buildbucket for the build status
[03:12:08.729] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:08.729] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:112:0) after 1m0s
[03:13:08.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:112:0)
[03:13:08.800] Timer tick, asking Buildbucket for the build status
[03:13:08.945] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:08.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:113:0) after 1m0s
[03:14:08.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:113:0)
[03:14:08.988] Timer tick, asking Buildbucket for the build status
[03:14:09.051] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:09.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:114:0) after 1m0s
[03:15:09.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:114:0)
[03:15:09.480] Timer tick, asking Buildbucket for the build status
[03:15:09.535] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:09.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:115:0) after 1m0s
[03:16:09.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:115:0)
[03:16:09.558] Timer tick, asking Buildbucket for the build status
[03:16:10.504] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:10.504] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:116:0) after 1m0s
[03:17:10.546] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:116:0)
[03:17:10.546] Timer tick, asking Buildbucket for the build status
[03:17:10.683] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:10.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:117:0) after 1m0s
[03:18:10.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:117:0)
[03:18:10.745] Timer tick, asking Buildbucket for the build status
[03:18:10.802] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:10.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:118:0) after 1m0s
[03:19:10.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:118:0)
[03:19:10.828] Timer tick, asking Buildbucket for the build status
[03:19:10.894] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:10.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:119:0) after 1m0s
[03:20:10.977] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:119:0)
[03:20:10.977] Timer tick, asking Buildbucket for the build status
[03:20:11.100] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:11.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:120:0) after 1m0s
[03:21:11.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:120:0)
[03:21:11.106] Timer tick, asking Buildbucket for the build status
[03:21:11.142] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:11.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:121:0) after 1m0s
[03:22:11.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:121:0)
[03:22:11.148] Timer tick, asking Buildbucket for the build status
[03:22:11.223] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:11.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:122:0) after 1m0s
[03:23:11.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:122:0)
[03:23:11.228] Timer tick, asking Buildbucket for the build status
[03:23:11.290] Build 8918145645990654832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:11.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9083575708002559856:123:0) after 1m0s
[03:23:35.340] Received PubSub notification, asking Buildbucket for the build status
[03:23:35.377] Build 8918145645990654832: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:23:35.377] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1553397815053033",
"created_by": "project:fuchsia",
"created_ts": "1553390569656244",
"failure_reason": "BUILD_FAILURE",
"id": "8918145645990654832",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"88f4054f48c56b816a555569ccd6e94c7072ab26\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-7887e815-us-central1-c-9k74\", \"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\": \"88f4054f48c56b816a555569ccd6e94c7072ab26\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-7887e815-us-central1-c-9k74\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4216-cf4a3cd\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1553390584454907",
"status": "COMPLETED",
"status_changed_ts": "1553390584895298",
"tags": [
"builder:clang",
"buildset:commit/git/88f4054f48c56b816a555569ccd6e94c7072ab26",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/88f4054f48c56b816a555569ccd6e94c7072ab26",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083575708002559856",
"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/8918145645990654832/+/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:43c61c929cba2610",
"user_agent:luci-scheduler"
],
"updated_ts": "1553397815076054",
"url": "https://ci.chromium.org/b/8918145645990654832",
"utcnow_ts": "1553397815369334"
}
}
[03:23:35.377] Invocation finished in 2h0m47.022688228s with status FAILED