[12:49:18.158] New invocation is queued and will start shortly
[12:49:19.226] Starting the invocation (attempt 1)
[12:49:19.317] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:49:19.317] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:49:19.317] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081176996263132624",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"b340497f7640344ed6c085645b450f9e02c8b20d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081176996263132624",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/b340497f7640344ed6c085645b450f9e02c8b20d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b340497f7640344ed6c085645b450f9e02c8b20d",
"gitiles_ref:refs/heads/master"
]
}
[12:49:20.890] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555678159394845",
"id": "8915746934293378368",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b340497f7640344ed6c085645b450f9e02c8b20d\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555678159629171",
"tags": [
"builder:clang",
"buildset:commit/git/b340497f7640344ed6c085645b450f9e02c8b20d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b340497f7640344ed6c085645b450f9e02c8b20d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081176996263132624",
"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/8915746934293378368/+/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": "1555678159629285",
"url": "https://ci.chromium.org/b/8915746934293378368",
"utcnow_ts": "1555678160876162"
}
}
[12:49:20.890] Task URL: https://ci.chromium.org/b/8915746934293378368
[12:49:20.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:2:0) after 1m0s
[12:49:36.072] Received PubSub notification, asking Buildbucket for the build status
[12:49:36.178] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:20.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:2:0)
[12:50:20.913] Timer tick, asking Buildbucket for the build status
[12:50:21.009] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:21.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:4:0) after 1m0s
[12:51:21.014] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:4:0)
[12:51:21.014] Timer tick, asking Buildbucket for the build status
[12:51:21.152] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:21.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:5:0) after 1m0s
[12:52:21.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:5:0)
[12:52:21.173] Timer tick, asking Buildbucket for the build status
[12:52:21.248] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:21.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:6:0) after 1m0s
[12:53:21.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:6:0)
[12:53:21.268] Timer tick, asking Buildbucket for the build status
[12:53:21.314] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:21.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:7:0) after 1m0s
[12:54:21.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:7:0)
[12:54:21.320] Timer tick, asking Buildbucket for the build status
[12:54:21.393] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:21.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:8:0) after 1m0s
[12:55:21.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:8:0)
[12:55:21.393] Timer tick, asking Buildbucket for the build status
[12:55:21.561] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:21.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:9:0) after 1m0s
[12:56:21.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:9:0)
[12:56:21.583] Timer tick, asking Buildbucket for the build status
[12:56:21.687] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:21.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:10:0) after 1m0s
[12:57:21.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:10:0)
[12:57:21.705] Timer tick, asking Buildbucket for the build status
[12:57:21.790] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:21.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:11:0) after 1m0s
[12:58:21.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:11:0)
[12:58:21.820] Timer tick, asking Buildbucket for the build status
[12:58:22.129] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:22.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:12:0) after 1m0s
[12:59:22.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:12:0)
[12:59:22.153] Timer tick, asking Buildbucket for the build status
[12:59:22.500] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:22.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:13:0) after 1m0s
[13:00:22.485] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:13:0)
[13:00:22.485] Timer tick, asking Buildbucket for the build status
[13:00:22.546] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:22.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:14:0) after 1m0s
[13:01:22.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:14:0)
[13:01:22.564] Timer tick, asking Buildbucket for the build status
[13:01:22.631] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:22.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:15:0) after 1m0s
[13:02:22.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:15:0)
[13:02:22.604] Timer tick, asking Buildbucket for the build status
[13:02:22.668] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:22.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:16:0) after 1m0s
[13:03:22.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:16:0)
[13:03:22.685] Timer tick, asking Buildbucket for the build status
[13:03:22.758] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:22.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:17:0) after 1m0s
[13:04:22.763] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:17:0)
[13:04:22.763] Timer tick, asking Buildbucket for the build status
[13:04:22.905] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:22.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:18:0) after 1m0s
[13:05:22.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:18:0)
[13:05:22.910] Timer tick, asking Buildbucket for the build status
[13:05:23.037] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:23.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:19:0) after 1m0s
[13:06:23.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:19:0)
[13:06:23.026] Timer tick, asking Buildbucket for the build status
[13:06:23.076] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:23.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:20:0) after 1m0s
[13:07:23.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:20:0)
[13:07:23.144] Timer tick, asking Buildbucket for the build status
[13:07:23.264] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:23.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:21:0) after 1m0s
[13:08:23.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:21:0)
[13:08:23.281] Timer tick, asking Buildbucket for the build status
[13:08:23.465] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:23.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:22:0) after 1m0s
[13:09:23.435] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:22:0)
[13:09:23.435] Timer tick, asking Buildbucket for the build status
[13:09:23.509] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:23.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:23:0) after 1m0s
[13:10:23.540] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:23:0)
[13:10:23.540] Timer tick, asking Buildbucket for the build status
[13:10:23.836] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:23.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:24:0) after 1m0s
[13:11:23.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:24:0)
[13:11:23.857] Timer tick, asking Buildbucket for the build status
[13:11:23.969] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:23.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:25:0) after 1m0s
[13:12:23.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:25:0)
[13:12:23.987] Timer tick, asking Buildbucket for the build status
[13:12:24.052] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:24.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:26:0) after 1m0s
[13:13:24.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:26:0)
[13:13:24.073] Timer tick, asking Buildbucket for the build status
[13:13:24.198] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:24.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:27:0) after 1m0s
[13:14:24.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:27:0)
[13:14:24.218] Timer tick, asking Buildbucket for the build status
[13:14:24.278] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:24.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:28:0) after 1m0s
[13:15:24.317] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:28:0)
[13:15:24.317] Timer tick, asking Buildbucket for the build status
[13:15:24.583] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:24.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:29:0) after 1m0s
[13:16:24.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:29:0)
[13:16:24.605] Timer tick, asking Buildbucket for the build status
[13:16:24.662] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:24.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:30:0) after 1m0s
[13:17:24.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:30:0)
[13:17:24.640] Timer tick, asking Buildbucket for the build status
[13:17:24.786] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:24.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:31:0) after 1m0s
[13:18:24.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:31:0)
[13:18:24.806] Timer tick, asking Buildbucket for the build status
[13:18:24.887] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:24.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:32:0) after 1m0s
[13:19:24.914] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:32:0)
[13:19:24.914] Timer tick, asking Buildbucket for the build status
[13:19:24.959] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:24.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:33:0) after 1m0s
[13:20:24.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:33:0)
[13:20:24.981] Timer tick, asking Buildbucket for the build status
[13:20:25.056] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:25.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:34:0) after 1m0s
[13:21:25.075] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:34:0)
[13:21:25.075] Timer tick, asking Buildbucket for the build status
[13:21:25.147] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:25.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:35:0) after 1m0s
[13:22:25.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:35:0)
[13:22:25.135] Timer tick, asking Buildbucket for the build status
[13:22:25.347] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:25.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:36:0) after 1m0s
[13:23:25.349] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:36:0)
[13:23:25.349] Timer tick, asking Buildbucket for the build status
[13:23:25.470] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:25.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:37:0) after 1m0s
[13:24:25.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:37:0)
[13:24:25.496] Timer tick, asking Buildbucket for the build status
[13:24:25.549] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:25.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:38:0) after 1m0s
[13:25:25.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:38:0)
[13:25:25.537] Timer tick, asking Buildbucket for the build status
[13:25:25.592] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:25.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:39:0) after 1m0s
[13:26:25.613] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:39:0)
[13:26:25.613] Timer tick, asking Buildbucket for the build status
[13:26:25.681] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:25.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:40:0) after 1m0s
[13:27:25.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:40:0)
[13:27:25.736] Timer tick, asking Buildbucket for the build status
[13:27:25.813] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:25.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:41:0) after 1m0s
[13:28:25.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:41:0)
[13:28:25.796] Timer tick, asking Buildbucket for the build status
[13:28:26.201] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:26.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:42:0) after 1m0s
[13:29:26.221] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:42:0)
[13:29:26.221] Timer tick, asking Buildbucket for the build status
[13:29:26.324] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:26.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:43:0) after 1m0s
[13:30:26.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:43:0)
[13:30:26.346] Timer tick, asking Buildbucket for the build status
[13:30:26.421] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:26.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:44:0) after 1m0s
[13:31:26.444] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:44:0)
[13:31:26.444] Timer tick, asking Buildbucket for the build status
[13:31:26.512] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:26.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:45:0) after 1m0s
[13:32:26.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:45:0)
[13:32:26.526] Timer tick, asking Buildbucket for the build status
[13:32:26.584] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:26.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:46:0) after 1m0s
[13:33:26.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:46:0)
[13:33:26.606] Timer tick, asking Buildbucket for the build status
[13:33:26.694] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:26.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:47:0) after 1m0s
[13:34:26.714] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:47:0)
[13:34:26.714] Timer tick, asking Buildbucket for the build status
[13:34:26.782] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:26.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:48:0) after 1m0s
[13:35:26.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:48:0)
[13:35:26.801] Timer tick, asking Buildbucket for the build status
[13:35:26.870] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:26.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:49:0) after 1m0s
[13:36:26.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:49:0)
[13:36:26.889] Timer tick, asking Buildbucket for the build status
[13:36:27.094] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:27.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:50:0) after 1m0s
[13:37:27.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:50:0)
[13:37:27.117] Timer tick, asking Buildbucket for the build status
[13:37:27.214] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:27.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:51:0) after 1m0s
[13:38:27.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:51:0)
[13:38:27.245] Timer tick, asking Buildbucket for the build status
[13:38:27.409] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:27.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:52:0) after 1m0s
[13:39:27.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:52:0)
[13:39:27.428] Timer tick, asking Buildbucket for the build status
[13:39:27.492] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:27.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:53:0) after 1m0s
[13:40:27.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:53:0)
[13:40:27.463] Timer tick, asking Buildbucket for the build status
[13:40:27.588] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:27.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:54:0) after 1m0s
[13:41:27.608] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:54:0)
[13:41:27.608] Timer tick, asking Buildbucket for the build status
[13:41:27.666] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:27.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:55:0) after 1m0s
[13:42:27.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:55:0)
[13:42:27.683] Timer tick, asking Buildbucket for the build status
[13:42:27.744] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:27.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:56:0) after 1m0s
[13:43:27.733] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:56:0)
[13:43:27.733] Timer tick, asking Buildbucket for the build status
[13:43:27.805] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:27.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:57:0) after 1m0s
[13:44:27.797] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:57:0)
[13:44:27.797] Timer tick, asking Buildbucket for the build status
[13:44:27.923] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:27.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:58:0) after 1m0s
[13:45:27.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:58:0)
[13:45:27.936] Timer tick, asking Buildbucket for the build status
[13:45:28.181] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:28.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:59:0) after 1m0s
[13:46:28.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:59:0)
[13:46:28.223] Timer tick, asking Buildbucket for the build status
[13:46:28.285] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:28.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:60:0) after 1m0s
[13:47:28.304] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:60:0)
[13:47:28.304] Timer tick, asking Buildbucket for the build status
[13:47:28.473] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:28.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:61:0) after 1m0s
[13:48:28.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:61:0)
[13:48:28.492] Timer tick, asking Buildbucket for the build status
[13:48:28.553] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:28.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:62:0) after 1m0s
[13:49:28.572] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:62:0)
[13:49:28.572] Timer tick, asking Buildbucket for the build status
[13:49:28.642] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:28.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:63:0) after 1m0s
[13:50:28.660] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:63:0)
[13:50:28.660] Timer tick, asking Buildbucket for the build status
[13:50:28.779] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:28.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:64:0) after 1m0s
[13:51:28.800] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:64:0)
[13:51:28.800] Timer tick, asking Buildbucket for the build status
[13:51:28.933] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:28.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:65:0) after 1m0s
[13:52:28.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:65:0)
[13:52:28.949] Timer tick, asking Buildbucket for the build status
[13:52:29.001] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:29.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:66:0) after 1m0s
[13:53:29.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:66:0)
[13:53:29.036] Timer tick, asking Buildbucket for the build status
[13:53:29.109] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:29.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:67:0) after 1m0s
[13:54:29.133] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:67:0)
[13:54:29.133] Timer tick, asking Buildbucket for the build status
[13:54:29.266] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:29.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:68:0) after 1m0s
[13:55:29.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:68:0)
[13:55:29.292] Timer tick, asking Buildbucket for the build status
[13:55:29.354] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:29.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:69:0) after 1m0s
[13:56:29.336] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:69:0)
[13:56:29.336] Timer tick, asking Buildbucket for the build status
[13:56:29.402] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:29.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:70:0) after 1m0s
[13:57:29.422] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:70:0)
[13:57:29.422] Timer tick, asking Buildbucket for the build status
[13:57:29.496] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:29.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:71:0) after 1m0s
[13:58:29.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:71:0)
[13:58:29.517] Timer tick, asking Buildbucket for the build status
[13:58:29.657] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:29.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:72:0) after 1m0s
[13:59:29.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:72:0)
[13:59:29.630] Timer tick, asking Buildbucket for the build status
[13:59:29.689] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:29.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:73:0) after 1m0s
[14:00:29.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:73:0)
[14:00:29.710] Timer tick, asking Buildbucket for the build status
[14:00:29.786] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:29.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:74:0) after 1m0s
[14:01:29.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:74:0)
[14:01:29.810] Timer tick, asking Buildbucket for the build status
[14:01:29.878] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:29.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:75:0) after 1m0s
[14:02:29.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:75:0)
[14:02:29.906] Timer tick, asking Buildbucket for the build status
[14:02:29.958] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:29.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:76:0) after 1m0s
[14:03:29.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:76:0)
[14:03:29.984] Timer tick, asking Buildbucket for the build status
[14:03:30.223] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:30.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:77:0) after 1m0s
[14:04:30.250] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:77:0)
[14:04:30.250] Timer tick, asking Buildbucket for the build status
[14:04:30.409] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:30.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:78:0) after 1m0s
[14:05:30.421] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:78:0)
[14:05:30.421] Timer tick, asking Buildbucket for the build status
[14:05:30.496] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:30.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:79:0) after 1m0s
[14:06:30.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:79:0)
[14:06:30.481] Timer tick, asking Buildbucket for the build status
[14:06:30.647] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:30.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:80:0) after 1m0s
[14:07:30.667] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:80:0)
[14:07:30.667] Timer tick, asking Buildbucket for the build status
[14:07:30.743] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:30.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:81:0) after 1m0s
[14:08:30.774] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:81:0)
[14:08:30.774] Timer tick, asking Buildbucket for the build status
[14:08:30.955] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:30.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:82:0) after 1m0s
[14:09:30.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:82:0)
[14:09:30.976] Timer tick, asking Buildbucket for the build status
[14:09:31.019] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:31.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:83:0) after 1m0s
[14:10:31.043] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:83:0)
[14:10:31.043] Timer tick, asking Buildbucket for the build status
[14:10:31.124] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:31.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:84:0) after 1m0s
[14:11:31.124] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:84:0)
[14:11:31.125] Timer tick, asking Buildbucket for the build status
[14:11:31.268] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:31.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:85:0) after 1m0s
[14:12:31.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:85:0)
[14:12:31.287] Timer tick, asking Buildbucket for the build status
[14:12:31.387] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:31.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:86:0) after 1m0s
[14:13:31.461] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:86:0)
[14:13:31.461] Timer tick, asking Buildbucket for the build status
[14:13:31.525] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:31.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:87:0) after 1m0s
[14:14:31.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:87:0)
[14:14:31.509] Timer tick, asking Buildbucket for the build status
[14:14:31.575] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:31.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:88:0) after 1m0s
[14:15:31.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:88:0)
[14:15:31.628] Timer tick, asking Buildbucket for the build status
[14:15:31.685] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:31.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:89:0) after 1m0s
[14:16:31.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:89:0)
[14:16:31.717] Timer tick, asking Buildbucket for the build status
[14:16:31.974] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:31.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:90:0) after 1m0s
[14:17:31.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:90:0)
[14:17:31.981] Timer tick, asking Buildbucket for the build status
[14:17:32.098] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:32.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:91:0) after 1m0s
[14:18:32.164] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:91:0)
[14:18:32.164] Timer tick, asking Buildbucket for the build status
[14:18:32.319] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:32.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:92:0) after 1m0s
[14:19:32.329] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:92:0)
[14:19:32.329] Timer tick, asking Buildbucket for the build status
[14:19:32.475] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:32.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:93:0) after 1m0s
[14:20:32.461] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:93:0)
[14:20:32.461] Timer tick, asking Buildbucket for the build status
[14:20:32.539] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:32.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:94:0) after 1m0s
[14:21:32.562] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:94:0)
[14:21:32.562] Timer tick, asking Buildbucket for the build status
[14:21:32.853] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:32.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:95:0) after 1m0s
[14:22:32.840] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:95:0)
[14:22:32.840] Timer tick, asking Buildbucket for the build status
[14:22:32.925] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:32.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:96:0) after 1m0s
[14:23:32.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:96:0)
[14:23:32.913] Timer tick, asking Buildbucket for the build status
[14:23:32.973] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:32.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:97:0) after 1m0s
[14:24:33.080] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:97:0)
[14:24:33.080] Timer tick, asking Buildbucket for the build status
[14:24:33.151] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:33.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:98:0) after 1m0s
[14:25:33.162] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:98:0)
[14:25:33.162] Timer tick, asking Buildbucket for the build status
[14:25:33.363] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:33.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:99:0) after 1m0s
[14:26:33.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:99:0)
[14:26:33.384] Timer tick, asking Buildbucket for the build status
[14:26:33.568] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:33.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:100:0) after 1m0s
[14:27:33.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:100:0)
[14:27:33.580] Timer tick, asking Buildbucket for the build status
[14:27:33.636] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:33.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:101:0) after 1m0s
[14:28:33.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:101:0)
[14:28:33.607] Timer tick, asking Buildbucket for the build status
[14:28:33.666] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:33.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:102:0) after 1m0s
[14:29:33.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:102:0)
[14:29:33.686] Timer tick, asking Buildbucket for the build status
[14:29:33.746] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:33.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:103:0) after 1m0s
[14:30:33.728] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:103:0)
[14:30:33.728] Timer tick, asking Buildbucket for the build status
[14:30:33.861] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:33.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:104:0) after 1m0s
[14:31:33.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:104:0)
[14:31:33.882] Timer tick, asking Buildbucket for the build status
[14:31:33.959] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:33.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:105:0) after 1m0s
[14:32:33.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:105:0)
[14:32:33.981] Timer tick, asking Buildbucket for the build status
[14:32:34.094] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:34.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:106:0) after 1m0s
[14:33:34.143] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:106:0)
[14:33:34.143] Timer tick, asking Buildbucket for the build status
[14:33:34.184] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:34.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:107:0) after 1m0s
[14:34:34.243] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:107:0)
[14:34:34.243] Timer tick, asking Buildbucket for the build status
[14:34:34.392] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:34.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:108:0) after 1m0s
[14:35:34.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:108:0)
[14:35:34.386] Timer tick, asking Buildbucket for the build status
[14:35:34.477] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:34.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:109:0) after 1m0s
[14:36:34.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:109:0)
[14:36:34.498] Timer tick, asking Buildbucket for the build status
[14:36:34.553] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:34.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:110:0) after 1m0s
[14:37:34.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:110:0)
[14:37:34.576] Timer tick, asking Buildbucket for the build status
[14:37:34.630] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:34.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:111:0) after 1m0s
[14:38:34.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:111:0)
[14:38:34.651] Timer tick, asking Buildbucket for the build status
[14:38:34.739] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:34.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:112:0) after 1m0s
[14:39:34.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:112:0)
[14:39:34.760] Timer tick, asking Buildbucket for the build status
[14:39:34.817] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:34.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:113:0) after 1m0s
[14:40:34.843] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:113:0)
[14:40:34.843] Timer tick, asking Buildbucket for the build status
[14:40:34.965] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:34.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:114:0) after 1m0s
[14:41:35.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:114:0)
[14:41:35.006] Timer tick, asking Buildbucket for the build status
[14:41:35.063] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:35.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:115:0) after 1m0s
[14:42:35.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:115:0)
[14:42:35.088] Timer tick, asking Buildbucket for the build status
[14:42:35.140] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:35.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:116:0) after 1m0s
[14:43:35.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:116:0)
[14:43:35.197] Timer tick, asking Buildbucket for the build status
[14:43:35.257] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:35.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:117:0) after 1m0s
[14:44:35.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:117:0)
[14:44:35.248] Timer tick, asking Buildbucket for the build status
[14:44:35.306] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:35.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:118:0) after 1m0s
[14:45:35.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:118:0)
[14:45:35.311] Timer tick, asking Buildbucket for the build status
[14:45:35.379] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:35.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:119:0) after 1m0s
[14:46:35.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:119:0)
[14:46:35.402] Timer tick, asking Buildbucket for the build status
[14:46:35.499] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:35.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:120:0) after 1m0s
[14:47:35.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:120:0)
[14:47:35.486] Timer tick, asking Buildbucket for the build status
[14:47:35.679] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:35.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:121:0) after 1m0s
[14:48:35.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:121:0)
[14:48:35.666] Timer tick, asking Buildbucket for the build status
[14:48:35.866] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:35.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:122:0) after 1m0s
[14:49:35.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:122:0)
[14:49:35.896] Timer tick, asking Buildbucket for the build status
[14:49:35.958] Build 8915746934293378368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:35.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081176996263132624:123:0) after 1m0s
[14:49:57.303] Received PubSub notification, asking Buildbucket for the build status
[14:49:57.349] Build 8915746934293378368: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:49:57.349] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1555685396871587",
"created_by": "project:fuchsia",
"created_ts": "1555678159394845",
"failure_reason": "BUILD_FAILURE",
"id": "8915746934293378368",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b340497f7640344ed6c085645b450f9e02c8b20d\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-4g9g\", \"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\": \"b340497f7640344ed6c085645b450f9e02c8b20d\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-4g9g\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4267-53b17d5\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1555678175394081",
"status": "COMPLETED",
"status_changed_ts": "1555678175877920",
"tags": [
"builder:clang",
"buildset:commit/git/b340497f7640344ed6c085645b450f9e02c8b20d",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b340497f7640344ed6c085645b450f9e02c8b20d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081176996263132624",
"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/8915746934293378368/+/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:444e76704eb99e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1555685396984642",
"url": "https://ci.chromium.org/b/8915746934293378368",
"utcnow_ts": "1555685397341976"
}
}
[14:49:57.349] Invocation finished in 2h0m39.217056538s with status FAILED