[14:14:50.135] New invocation is queued and will start shortly
[14:14:51.160] Starting the invocation (attempt 1)
[14:14:51.203] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:14:51.203] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:14:51.203] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081262211952101440",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"b8f82ca1b2b232cb84c66e72efecfb51bca517fb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081262211952101440",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/b8f82ca1b2b232cb84c66e72efecfb51bca517fb",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b8f82ca1b2b232cb84c66e72efecfb51bca517fb",
"gitiles_ref:refs/heads/master"
]
}
[14:14:51.974] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555596891350003",
"id": "8915832150014107312",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b8f82ca1b2b232cb84c66e72efecfb51bca517fb\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555596891606696",
"tags": [
"builder:clang",
"buildset:commit/git/b8f82ca1b2b232cb84c66e72efecfb51bca517fb",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b8f82ca1b2b232cb84c66e72efecfb51bca517fb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081262211952101440",
"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/8915832150014107312/+/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": "1555596891606799",
"url": "https://ci.chromium.org/b/8915832150014107312",
"utcnow_ts": "1555596891951408"
}
}
[14:14:51.974] Task URL: https://ci.chromium.org/b/8915832150014107312
[14:14:51.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:2:0) after 1m0s
[14:15:10.180] Received PubSub notification, asking Buildbucket for the build status
[14:15:10.287] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:51.991] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:2:0)
[14:15:51.991] Timer tick, asking Buildbucket for the build status
[14:15:52.066] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:52.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:4:0) after 1m0s
[14:16:52.038] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:4:0)
[14:16:52.038] Timer tick, asking Buildbucket for the build status
[14:16:52.139] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:52.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:5:0) after 1m0s
[14:17:52.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:5:0)
[14:17:52.115] Timer tick, asking Buildbucket for the build status
[14:17:52.207] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:52.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:6:0) after 1m0s
[14:18:52.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:6:0)
[14:18:52.223] Timer tick, asking Buildbucket for the build status
[14:18:52.267] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:52.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:7:0) after 1m0s
[14:19:52.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:7:0)
[14:19:52.286] Timer tick, asking Buildbucket for the build status
[14:19:52.419] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:52.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:8:0) after 1m0s
[14:20:52.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:8:0)
[14:20:52.439] Timer tick, asking Buildbucket for the build status
[14:20:52.514] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:52.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:9:0) after 1m0s
[14:21:52.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:9:0)
[14:21:52.534] Timer tick, asking Buildbucket for the build status
[14:21:52.622] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:52.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:10:0) after 1m0s
[14:22:52.639] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:10:0)
[14:22:52.639] Timer tick, asking Buildbucket for the build status
[14:22:52.695] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:52.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:11:0) after 1m0s
[14:23:52.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:11:0)
[14:23:52.713] Timer tick, asking Buildbucket for the build status
[14:23:52.844] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:52.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:12:0) after 1m0s
[14:24:52.840] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:12:0)
[14:24:52.840] Timer tick, asking Buildbucket for the build status
[14:24:52.881] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:52.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:13:0) after 1m0s
[14:25:52.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:13:0)
[14:25:52.884] Timer tick, asking Buildbucket for the build status
[14:25:52.930] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:52.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:14:0) after 1m0s
[14:26:52.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:14:0)
[14:26:52.949] Timer tick, asking Buildbucket for the build status
[14:26:53.004] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:53.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:15:0) after 1m0s
[14:27:52.972] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:15:0)
[14:27:52.972] Timer tick, asking Buildbucket for the build status
[14:27:53.326] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:53.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:16:0) after 1m0s
[14:28:53.341] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:16:0)
[14:28:53.341] Timer tick, asking Buildbucket for the build status
[14:28:53.393] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:53.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:17:0) after 1m0s
[14:29:53.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:17:0)
[14:29:53.430] Timer tick, asking Buildbucket for the build status
[14:29:53.692] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:53.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:18:0) after 1m0s
[14:30:53.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:18:0)
[14:30:53.716] Timer tick, asking Buildbucket for the build status
[14:30:53.911] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:53.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:19:0) after 1m0s
[14:31:53.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:19:0)
[14:31:53.912] Timer tick, asking Buildbucket for the build status
[14:31:54.066] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:54.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:20:0) after 1m0s
[14:32:54.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:20:0)
[14:32:54.102] Timer tick, asking Buildbucket for the build status
[14:32:54.147] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:54.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:21:0) after 1m0s
[14:33:54.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:21:0)
[14:33:54.167] Timer tick, asking Buildbucket for the build status
[14:33:54.264] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:54.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:22:0) after 1m0s
[14:34:54.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:22:0)
[14:34:54.283] Timer tick, asking Buildbucket for the build status
[14:34:54.378] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:54.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:23:0) after 1m0s
[14:35:54.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:23:0)
[14:35:54.399] Timer tick, asking Buildbucket for the build status
[14:35:54.452] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:54.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:24:0) after 1m0s
[14:36:54.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:24:0)
[14:36:54.471] Timer tick, asking Buildbucket for the build status
[14:36:54.699] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:54.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:25:0) after 1m0s
[14:37:54.721] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:25:0)
[14:37:54.721] Timer tick, asking Buildbucket for the build status
[14:37:54.773] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:54.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:26:0) after 1m0s
[14:38:54.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:26:0)
[14:38:54.796] Timer tick, asking Buildbucket for the build status
[14:38:54.857] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:54.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:27:0) after 1m0s
[14:39:54.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:27:0)
[14:39:54.876] Timer tick, asking Buildbucket for the build status
[14:39:54.983] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:54.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:28:0) after 1m0s
[14:40:54.962] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:28:0)
[14:40:54.962] Timer tick, asking Buildbucket for the build status
[14:40:55.187] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:55.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:29:0) after 1m0s
[14:41:55.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:29:0)
[14:41:55.188] Timer tick, asking Buildbucket for the build status
[14:41:55.316] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:55.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:30:0) after 1m0s
[14:42:55.356] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:30:0)
[14:42:55.356] Timer tick, asking Buildbucket for the build status
[14:42:55.591] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:55.591] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:31:0) after 1m0s
[14:43:55.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:31:0)
[14:43:55.590] Timer tick, asking Buildbucket for the build status
[14:43:55.658] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:55.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:32:0) after 1m0s
[14:44:55.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:32:0)
[14:44:55.685] Timer tick, asking Buildbucket for the build status
[14:44:55.851] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:55.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:33:0) after 1m0s
[14:45:55.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:33:0)
[14:45:55.875] Timer tick, asking Buildbucket for the build status
[14:45:55.946] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:55.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:34:0) after 1m0s
[14:46:55.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:34:0)
[14:46:55.964] Timer tick, asking Buildbucket for the build status
[14:46:56.154] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:56.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:35:0) after 1m0s
[14:47:56.178] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:35:0)
[14:47:56.178] Timer tick, asking Buildbucket for the build status
[14:47:56.249] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:56.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:36:0) after 1m0s
[14:48:56.248] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:36:0)
[14:48:56.248] Timer tick, asking Buildbucket for the build status
[14:48:56.293] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:56.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:37:0) after 1m0s
[14:49:56.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:37:0)
[14:49:56.283] Timer tick, asking Buildbucket for the build status
[14:49:56.340] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:56.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:38:0) after 1m0s
[14:50:56.378] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:38:0)
[14:50:56.378] Timer tick, asking Buildbucket for the build status
[14:50:56.550] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:56.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:39:0) after 1m0s
[14:51:56.570] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:39:0)
[14:51:56.570] Timer tick, asking Buildbucket for the build status
[14:51:56.691] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:56.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:40:0) after 1m0s
[14:52:56.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:40:0)
[14:52:56.668] Timer tick, asking Buildbucket for the build status
[14:52:56.809] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:56.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:41:0) after 1m0s
[14:53:56.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:41:0)
[14:53:56.799] Timer tick, asking Buildbucket for the build status
[14:53:56.853] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:56.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:42:0) after 1m0s
[14:54:56.860] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:42:0)
[14:54:56.860] Timer tick, asking Buildbucket for the build status
[14:54:56.929] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:56.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:43:0) after 1m0s
[14:55:56.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:43:0)
[14:55:56.913] Timer tick, asking Buildbucket for the build status
[14:55:56.990] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:56.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:44:0) after 1m0s
[14:56:57.014] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:44:0)
[14:56:57.014] Timer tick, asking Buildbucket for the build status
[14:56:57.132] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:57.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:45:0) after 1m0s
[14:57:57.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:45:0)
[14:57:57.197] Timer tick, asking Buildbucket for the build status
[14:57:57.258] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:57.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:46:0) after 1m0s
[14:58:57.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:46:0)
[14:58:57.280] Timer tick, asking Buildbucket for the build status
[14:58:57.475] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:57.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:47:0) after 1m0s
[14:59:57.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:47:0)
[14:59:57.496] Timer tick, asking Buildbucket for the build status
[14:59:57.679] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:57.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:48:0) after 1m0s
[15:00:57.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:48:0)
[15:00:57.703] Timer tick, asking Buildbucket for the build status
[15:00:57.791] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:57.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:49:0) after 1m0s
[15:01:57.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:49:0)
[15:01:57.807] Timer tick, asking Buildbucket for the build status
[15:01:57.867] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:57.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:50:0) after 1m0s
[15:02:58.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:50:0)
[15:02:58.099] Timer tick, asking Buildbucket for the build status
[15:02:58.152] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:58.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:51:0) after 1m0s
[15:03:58.167] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:51:0)
[15:03:58.167] Timer tick, asking Buildbucket for the build status
[15:03:58.371] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:58.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:52:0) after 1m0s
[15:04:58.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:52:0)
[15:04:58.389] Timer tick, asking Buildbucket for the build status
[15:04:58.506] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:58.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:53:0) after 1m0s
[15:05:58.527] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:53:0)
[15:05:58.527] Timer tick, asking Buildbucket for the build status
[15:05:58.606] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:58.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:54:0) after 1m0s
[15:06:58.627] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:54:0)
[15:06:58.627] Timer tick, asking Buildbucket for the build status
[15:06:58.691] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:58.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:55:0) after 1m0s
[15:07:58.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:55:0)
[15:07:58.742] Timer tick, asking Buildbucket for the build status
[15:07:58.799] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:58.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:56:0) after 1m0s
[15:08:58.817] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:56:0)
[15:08:58.817] Timer tick, asking Buildbucket for the build status
[15:08:58.900] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:58.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:57:0) after 1m0s
[15:09:58.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:57:0)
[15:09:58.920] Timer tick, asking Buildbucket for the build status
[15:09:58.989] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:58.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:58:0) after 1m0s
[15:10:59.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:58:0)
[15:10:59.009] Timer tick, asking Buildbucket for the build status
[15:10:59.096] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:59.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:59:0) after 1m0s
[15:11:59.068] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:59:0)
[15:11:59.068] Timer tick, asking Buildbucket for the build status
[15:11:59.358] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:59.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:60:0) after 1m0s
[15:12:59.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:60:0)
[15:12:59.385] Timer tick, asking Buildbucket for the build status
[15:12:59.435] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:59.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:61:0) after 1m0s
[15:13:59.434] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:61:0)
[15:13:59.434] Timer tick, asking Buildbucket for the build status
[15:13:59.530] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:59.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:62:0) after 1m0s
[15:14:59.570] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:62:0)
[15:14:59.570] Timer tick, asking Buildbucket for the build status
[15:14:59.714] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:59.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:63:0) after 1m0s
[15:15:59.735] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:63:0)
[15:15:59.735] Timer tick, asking Buildbucket for the build status
[15:15:59.867] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:59.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:64:0) after 1m0s
[15:16:59.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:64:0)
[15:16:59.866] Timer tick, asking Buildbucket for the build status
[15:16:59.950] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:59.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:65:0) after 1m0s
[15:18:00.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:65:0)
[15:18:00.027] Timer tick, asking Buildbucket for the build status
[15:18:00.118] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:00.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:66:0) after 1m0s
[15:19:00.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:66:0)
[15:19:00.115] Timer tick, asking Buildbucket for the build status
[15:19:00.171] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:00.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:67:0) after 1m0s
[15:20:00.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:67:0)
[15:20:00.189] Timer tick, asking Buildbucket for the build status
[15:20:00.291] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:00.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:68:0) after 1m0s
[15:21:00.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:68:0)
[15:21:00.325] Timer tick, asking Buildbucket for the build status
[15:21:00.389] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:00.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:69:0) after 1m0s
[15:22:00.425] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:69:0)
[15:22:00.425] Timer tick, asking Buildbucket for the build status
[15:22:00.520] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:00.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:70:0) after 1m0s
[15:23:00.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:70:0)
[15:23:00.518] Timer tick, asking Buildbucket for the build status
[15:23:00.811] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:00.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:71:0) after 1m0s
[15:24:00.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:71:0)
[15:24:00.832] Timer tick, asking Buildbucket for the build status
[15:24:00.900] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:00.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:72:0) after 1m0s
[15:25:00.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:72:0)
[15:25:00.994] Timer tick, asking Buildbucket for the build status
[15:25:01.041] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:01.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:73:0) after 1m0s
[15:26:01.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:73:0)
[15:26:01.071] Timer tick, asking Buildbucket for the build status
[15:26:01.248] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:01.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:74:0) after 1m0s
[15:27:01.270] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:74:0)
[15:27:01.270] Timer tick, asking Buildbucket for the build status
[15:27:01.318] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:01.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:75:0) after 1m0s
[15:28:01.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:75:0)
[15:28:01.340] Timer tick, asking Buildbucket for the build status
[15:28:01.636] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:01.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:76:0) after 1m0s
[15:29:01.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:76:0)
[15:29:01.656] Timer tick, asking Buildbucket for the build status
[15:29:01.802] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:01.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:77:0) after 1m0s
[15:30:01.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:77:0)
[15:30:01.826] Timer tick, asking Buildbucket for the build status
[15:30:02.326] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:02.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:78:0) after 1m0s
[15:31:02.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:78:0)
[15:31:02.338] Timer tick, asking Buildbucket for the build status
[15:31:02.396] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:02.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:79:0) after 1m0s
[15:32:02.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:79:0)
[15:32:02.401] Timer tick, asking Buildbucket for the build status
[15:32:09.363] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:09.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:80:0) after 1m0s
[15:33:09.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:80:0)
[15:33:09.391] Timer tick, asking Buildbucket for the build status
[15:33:09.463] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:09.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:81:0) after 1m0s
[15:34:09.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:81:0)
[15:34:09.503] Timer tick, asking Buildbucket for the build status
[15:34:09.632] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:09.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:82:0) after 1m0s
[15:35:09.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:82:0)
[15:35:09.656] Timer tick, asking Buildbucket for the build status
[15:35:09.916] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:09.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:83:0) after 1m0s
[15:36:09.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:83:0)
[15:36:09.938] Timer tick, asking Buildbucket for the build status
[15:36:10.015] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:10.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:84:0) after 1m0s
[15:37:10.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:84:0)
[15:37:10.053] Timer tick, asking Buildbucket for the build status
[15:37:10.114] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:10.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:85:0) after 1m0s
[15:38:10.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:85:0)
[15:38:10.103] Timer tick, asking Buildbucket for the build status
[15:38:10.430] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:10.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:86:0) after 1m0s
[15:39:10.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:86:0)
[15:39:10.458] Timer tick, asking Buildbucket for the build status
[15:39:10.533] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:10.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:87:0) after 1m0s
[15:40:10.554] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:87:0)
[15:40:10.554] Timer tick, asking Buildbucket for the build status
[15:40:10.812] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:10.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:88:0) after 1m0s
[15:41:10.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:88:0)
[15:41:10.833] Timer tick, asking Buildbucket for the build status
[15:41:10.910] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:10.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:89:0) after 1m0s
[15:42:10.929] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:89:0)
[15:42:10.929] Timer tick, asking Buildbucket for the build status
[15:42:10.978] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:10.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:90:0) after 1m0s
[15:43:11.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:90:0)
[15:43:11.017] Timer tick, asking Buildbucket for the build status
[15:43:11.214] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:11.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:91:0) after 1m0s
[15:44:11.234] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:91:0)
[15:44:11.234] Timer tick, asking Buildbucket for the build status
[15:44:11.279] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:11.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:92:0) after 1m0s
[15:45:11.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:92:0)
[15:45:11.321] Timer tick, asking Buildbucket for the build status
[15:45:11.381] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:11.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:93:0) after 1m0s
[15:46:11.408] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:93:0)
[15:46:11.408] Timer tick, asking Buildbucket for the build status
[15:46:11.575] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:11.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:94:0) after 1m0s
[15:47:11.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:94:0)
[15:47:11.595] Timer tick, asking Buildbucket for the build status
[15:47:11.759] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:11.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:95:0) after 1m0s
[15:48:11.842] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:95:0)
[15:48:11.842] Timer tick, asking Buildbucket for the build status
[15:48:11.893] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:11.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:96:0) after 1m0s
[15:49:11.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:96:0)
[15:49:11.918] Timer tick, asking Buildbucket for the build status
[15:49:12.085] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:12.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:97:0) after 1m0s
[15:50:12.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:97:0)
[15:50:12.111] Timer tick, asking Buildbucket for the build status
[15:50:12.350] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:12.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:98:0) after 1m0s
[15:51:12.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:98:0)
[15:51:12.379] Timer tick, asking Buildbucket for the build status
[15:51:12.463] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:12.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:99:0) after 1m0s
[15:52:12.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:99:0)
[15:52:12.484] Timer tick, asking Buildbucket for the build status
[15:52:12.592] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:12.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:100:0) after 1m0s
[15:53:12.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:100:0)
[15:53:12.615] Timer tick, asking Buildbucket for the build status
[15:53:12.672] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:12.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:101:0) after 1m0s
[15:54:12.702] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:101:0)
[15:54:12.702] Timer tick, asking Buildbucket for the build status
[15:54:12.798] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:12.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:102:0) after 1m0s
[15:55:12.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:102:0)
[15:55:12.822] Timer tick, asking Buildbucket for the build status
[15:55:12.884] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:12.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:103:0) after 1m0s
[15:56:12.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:103:0)
[15:56:12.897] Timer tick, asking Buildbucket for the build status
[15:56:12.964] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:12.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:104:0) after 1m0s
[15:57:13.046] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:104:0)
[15:57:13.046] Timer tick, asking Buildbucket for the build status
[15:57:13.366] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:13.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:105:0) after 1m0s
[15:58:13.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:105:0)
[15:58:13.464] Timer tick, asking Buildbucket for the build status
[15:58:13.521] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:13.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:106:0) after 1m0s
[15:59:13.546] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:106:0)
[15:59:13.546] Timer tick, asking Buildbucket for the build status
[15:59:13.753] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:13.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:107:0) after 1m0s
[16:00:13.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:107:0)
[16:00:13.845] Timer tick, asking Buildbucket for the build status
[16:00:13.894] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:13.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:108:0) after 1m0s
[16:01:13.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:108:0)
[16:01:13.998] Timer tick, asking Buildbucket for the build status
[16:01:14.053] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:14.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:109:0) after 1m0s
[16:02:14.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:109:0)
[16:02:14.101] Timer tick, asking Buildbucket for the build status
[16:02:14.154] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:14.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:110:0) after 1m0s
[16:03:14.180] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:110:0)
[16:03:14.180] Timer tick, asking Buildbucket for the build status
[16:03:14.399] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:14.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:111:0) after 1m0s
[16:04:14.462] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:111:0)
[16:04:14.462] Timer tick, asking Buildbucket for the build status
[16:04:14.615] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:14.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:112:0) after 1m0s
[16:05:14.663] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:112:0)
[16:05:14.663] Timer tick, asking Buildbucket for the build status
[16:05:14.733] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:14.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:113:0) after 1m0s
[16:06:14.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:113:0)
[16:06:14.754] Timer tick, asking Buildbucket for the build status
[16:06:14.807] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:14.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:114:0) after 1m0s
[16:07:14.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:114:0)
[16:07:14.831] Timer tick, asking Buildbucket for the build status
[16:07:14.905] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:14.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:115:0) after 1m0s
[16:08:14.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:115:0)
[16:08:14.938] Timer tick, asking Buildbucket for the build status
[16:08:14.980] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:14.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:116:0) after 1m0s
[16:09:15.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:116:0)
[16:09:15.004] Timer tick, asking Buildbucket for the build status
[16:09:15.093] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:15.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:117:0) after 1m0s
[16:10:15.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:117:0)
[16:10:15.086] Timer tick, asking Buildbucket for the build status
[16:10:15.186] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:15.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:118:0) after 1m0s
[16:11:15.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:118:0)
[16:11:15.208] Timer tick, asking Buildbucket for the build status
[16:11:15.252] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:15.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:119:0) after 1m0s
[16:12:15.280] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:119:0)
[16:12:15.280] Timer tick, asking Buildbucket for the build status
[16:12:15.556] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:15.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:120:0) after 1m0s
[16:13:15.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:120:0)
[16:13:15.597] Timer tick, asking Buildbucket for the build status
[16:13:15.670] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:15.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:121:0) after 1m0s
[16:14:15.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:121:0)
[16:14:15.677] Timer tick, asking Buildbucket for the build status
[16:14:15.797] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:15.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:122:0) after 1m0s
[16:15:15.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:122:0)
[16:15:15.821] Timer tick, asking Buildbucket for the build status
[16:15:15.945] Build 8915832150014107312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:15.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081262211952101440:123:0) after 1m0s
[16:15:32.299] Received PubSub notification, asking Buildbucket for the build status
[16:15:32.381] Build 8915832150014107312: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:15:32.381] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555604131957077",
"created_by": "project:fuchsia",
"created_ts": "1555596891350003",
"failure_reason": "BUILD_FAILURE",
"id": "8915832150014107312",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b8f82ca1b2b232cb84c66e72efecfb51bca517fb\"}}",
"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-jtgj\", \"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\": \"b8f82ca1b2b232cb84c66e72efecfb51bca517fb\", \"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-jtgj\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4260-fb32526\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1555596909428514",
"status": "COMPLETED",
"status_changed_ts": "1555596909905959",
"tags": [
"builder:clang",
"buildset:commit/git/b8f82ca1b2b232cb84c66e72efecfb51bca517fb",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b8f82ca1b2b232cb84c66e72efecfb51bca517fb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081262211952101440",
"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/8915832150014107312/+/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:44499e6092bf2e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1555604131998311",
"url": "https://ci.chromium.org/b/8915832150014107312",
"utcnow_ts": "1555604132369890"
}
}
[16:15:32.381] Invocation finished in 2h0m42.262817407s with status FAILED