[22:15:12.616] New invocation is queued and will start shortly
[22:15:13.704] Starting the invocation (attempt 1)
[22:15:13.736] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:15:13.736] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:15:13.736] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9081866168152928416",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"57c3d4bed3ee625e548154063e9f6e7ea70d6ed4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9081866168152928416",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/57c3d4bed3ee625e548154063e9f6e7ea70d6ed4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/57c3d4bed3ee625e548154063e9f6e7ea70d6ed4",
"gitiles_ref:refs/heads/master"
]
}
[22:15:14.360] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1555020913808289",
"id": "8916436106240693984",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"57c3d4bed3ee625e548154063e9f6e7ea70d6ed4\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555020914169153",
"tags": [
"builder:clang",
"buildset:commit/git/57c3d4bed3ee625e548154063e9f6e7ea70d6ed4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/57c3d4bed3ee625e548154063e9f6e7ea70d6ed4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081866168152928416",
"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/8916436106240693984/+/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": "1555020914169285",
"url": "https://ci.chromium.org/b/8916436106240693984",
"utcnow_ts": "1555020914348335"
}
}
[22:15:14.360] Task URL: https://ci.chromium.org/b/8916436106240693984
[22:15:14.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:2:0) after 1m0s
[22:15:16.987] Received PubSub notification, asking Buildbucket for the build status
[22:15:17.036] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:14.376] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:2:0)
[22:16:14.376] Timer tick, asking Buildbucket for the build status
[22:16:14.491] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:14.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:4:0) after 1m0s
[22:17:14.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:4:0)
[22:17:14.507] Timer tick, asking Buildbucket for the build status
[22:17:14.564] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:14.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:5:0) after 1m0s
[22:18:14.566] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:5:0)
[22:18:14.566] Timer tick, asking Buildbucket for the build status
[22:18:14.684] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:14.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:6:0) after 1m0s
[22:19:14.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:6:0)
[22:19:14.694] Timer tick, asking Buildbucket for the build status
[22:19:14.831] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:14.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:7:0) after 1m0s
[22:20:14.847] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:7:0)
[22:20:14.848] Timer tick, asking Buildbucket for the build status
[22:20:14.889] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:14.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:8:0) after 1m0s
[22:21:14.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:8:0)
[22:21:14.909] Timer tick, asking Buildbucket for the build status
[22:21:15.009] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:15.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:9:0) after 1m0s
[22:22:15.011] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:9:0)
[22:22:15.011] Timer tick, asking Buildbucket for the build status
[22:22:15.067] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:15.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:10:0) after 1m0s
[22:23:15.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:10:0)
[22:23:15.087] Timer tick, asking Buildbucket for the build status
[22:23:15.169] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:15.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:11:0) after 1m0s
[22:24:15.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:11:0)
[22:24:15.186] Timer tick, asking Buildbucket for the build status
[22:24:15.376] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:15.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:12:0) after 1m0s
[22:25:15.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:12:0)
[22:25:15.351] Timer tick, asking Buildbucket for the build status
[22:25:15.442] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:15.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:13:0) after 1m0s
[22:26:15.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:13:0)
[22:26:15.460] Timer tick, asking Buildbucket for the build status
[22:26:15.514] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:15.514] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:14:0) after 1m0s
[22:27:15.520] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:14:0)
[22:27:15.520] Timer tick, asking Buildbucket for the build status
[22:27:15.578] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:15.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:15:0) after 1m0s
[22:28:15.586] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:15:0)
[22:28:15.586] Timer tick, asking Buildbucket for the build status
[22:28:15.626] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:15.626] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:16:0) after 1m0s
[22:29:15.601] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:16:0)
[22:29:15.601] Timer tick, asking Buildbucket for the build status
[22:29:15.644] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:15.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:17:0) after 1m0s
[22:30:15.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:17:0)
[22:30:15.662] Timer tick, asking Buildbucket for the build status
[22:30:15.706] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:15.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:18:0) after 1m0s
[22:31:15.721] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:18:0)
[22:31:15.721] Timer tick, asking Buildbucket for the build status
[22:31:15.924] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:15.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:19:0) after 1m0s
[22:32:15.895] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:19:0)
[22:32:15.895] Timer tick, asking Buildbucket for the build status
[22:32:15.939] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:15.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:20:0) after 1m0s
[22:33:15.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:20:0)
[22:33:15.959] Timer tick, asking Buildbucket for the build status
[22:33:16.094] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:16.094] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:21:0) after 1m0s
[22:34:16.117] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:21:0)
[22:34:16.117] Timer tick, asking Buildbucket for the build status
[22:34:16.154] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:16.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:22:0) after 1m0s
[22:35:16.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:22:0)
[22:35:16.131] Timer tick, asking Buildbucket for the build status
[22:35:16.197] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:16.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:23:0) after 1m0s
[22:36:16.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:23:0)
[22:36:16.208] Timer tick, asking Buildbucket for the build status
[22:36:16.265] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:16.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:24:0) after 1m0s
[22:37:16.288] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:24:0)
[22:37:16.288] Timer tick, asking Buildbucket for the build status
[22:37:16.353] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:16.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:25:0) after 1m0s
[22:38:16.371] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:25:0)
[22:38:16.371] Timer tick, asking Buildbucket for the build status
[22:38:16.512] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:16.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:26:0) after 1m0s
[22:39:16.562] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:26:0)
[22:39:16.562] Timer tick, asking Buildbucket for the build status
[22:39:16.601] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:16.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:27:0) after 1m0s
[22:40:16.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:27:0)
[22:40:16.622] Timer tick, asking Buildbucket for the build status
[22:40:16.843] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:16.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:28:0) after 1m0s
[22:41:16.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:28:0)
[22:41:16.859] Timer tick, asking Buildbucket for the build status
[22:41:16.957] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:16.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:29:0) after 1m0s
[22:42:16.978] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:29:0)
[22:42:16.978] Timer tick, asking Buildbucket for the build status
[22:42:17.032] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:17.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:30:0) after 1m0s
[22:43:17.062] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:30:0)
[22:43:17.062] Timer tick, asking Buildbucket for the build status
[22:43:17.114] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:17.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:31:0) after 1m0s
[22:44:17.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:31:0)
[22:44:17.144] Timer tick, asking Buildbucket for the build status
[22:44:17.282] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:17.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:32:0) after 1m0s
[22:45:17.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:32:0)
[22:45:17.301] Timer tick, asking Buildbucket for the build status
[22:45:17.392] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:17.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:33:0) after 1m0s
[22:46:17.470] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:33:0)
[22:46:17.470] Timer tick, asking Buildbucket for the build status
[22:46:17.586] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:17.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:34:0) after 1m0s
[22:47:17.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:34:0)
[22:47:17.605] Timer tick, asking Buildbucket for the build status
[22:47:17.639] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:17.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:35:0) after 1m0s
[22:48:17.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:35:0)
[22:48:17.620] Timer tick, asking Buildbucket for the build status
[22:48:17.668] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:17.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:36:0) after 1m0s
[22:49:17.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:36:0)
[22:49:17.694] Timer tick, asking Buildbucket for the build status
[22:49:17.773] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:17.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:37:0) after 1m0s
[22:50:17.797] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:37:0)
[22:50:17.797] Timer tick, asking Buildbucket for the build status
[22:50:17.834] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:17.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:38:0) after 1m0s
[22:51:17.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:38:0)
[22:51:17.819] Timer tick, asking Buildbucket for the build status
[22:51:17.855] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:17.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:39:0) after 1m0s
[22:52:17.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:39:0)
[22:52:17.879] Timer tick, asking Buildbucket for the build status
[22:52:18.156] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:18.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:40:0) after 1m0s
[22:53:18.184] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:40:0)
[22:53:18.184] Timer tick, asking Buildbucket for the build status
[22:53:18.247] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:18.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:41:0) after 1m0s
[22:54:18.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:41:0)
[22:54:18.268] Timer tick, asking Buildbucket for the build status
[22:54:18.308] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:18.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:42:0) after 1m0s
[22:55:18.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:42:0)
[22:55:18.318] Timer tick, asking Buildbucket for the build status
[22:55:18.474] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:18.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:43:0) after 1m0s
[22:56:18.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:43:0)
[22:56:18.491] Timer tick, asking Buildbucket for the build status
[22:56:18.539] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:18.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:44:0) after 1m0s
[22:57:18.561] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:44:0)
[22:57:18.561] Timer tick, asking Buildbucket for the build status
[22:57:18.897] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:18.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:45:0) after 1m0s
[22:58:18.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:45:0)
[22:58:18.912] Timer tick, asking Buildbucket for the build status
[22:58:19.081] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:19.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:46:0) after 1m0s
[22:59:19.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:46:0)
[22:59:19.104] Timer tick, asking Buildbucket for the build status
[22:59:19.159] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:19.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:47:0) after 1m0s
[23:00:19.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:47:0)
[23:00:19.183] Timer tick, asking Buildbucket for the build status
[23:00:19.302] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:19.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:48:0) after 1m0s
[23:01:19.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:48:0)
[23:01:19.318] Timer tick, asking Buildbucket for the build status
[23:01:19.372] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:19.372] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:49:0) after 1m0s
[23:02:19.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:49:0)
[23:02:19.342] Timer tick, asking Buildbucket for the build status
[23:02:19.401] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:19.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:50:0) after 1m0s
[23:03:19.417] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:50:0)
[23:03:19.417] Timer tick, asking Buildbucket for the build status
[23:03:19.454] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:19.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:51:0) after 1m0s
[23:04:19.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:51:0)
[23:04:19.449] Timer tick, asking Buildbucket for the build status
[23:04:19.543] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:19.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:52:0) after 1m0s
[23:05:19.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:52:0)
[23:05:19.560] Timer tick, asking Buildbucket for the build status
[23:05:19.610] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:19.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:53:0) after 1m0s
[23:06:19.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:53:0)
[23:06:19.630] Timer tick, asking Buildbucket for the build status
[23:06:19.716] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:19.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:54:0) after 1m0s
[23:07:19.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:54:0)
[23:07:19.727] Timer tick, asking Buildbucket for the build status
[23:07:19.816] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:19.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:55:0) after 1m0s
[23:08:19.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:55:0)
[23:08:19.827] Timer tick, asking Buildbucket for the build status
[23:08:20.001] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:20.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:56:0) after 1m0s
[23:09:20.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:56:0)
[23:09:20.019] Timer tick, asking Buildbucket for the build status
[23:09:20.098] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:20.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:57:0) after 1m0s
[23:10:20.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:57:0)
[23:10:20.123] Timer tick, asking Buildbucket for the build status
[23:10:20.194] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:20.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:58:0) after 1m0s
[23:11:20.249] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:58:0)
[23:11:20.249] Timer tick, asking Buildbucket for the build status
[23:11:20.378] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:20.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:59:0) after 1m0s
[23:12:20.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:59:0)
[23:12:20.382] Timer tick, asking Buildbucket for the build status
[23:12:20.495] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:20.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:60:0) after 1m0s
[23:13:20.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:60:0)
[23:13:20.484] Timer tick, asking Buildbucket for the build status
[23:13:20.599] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:20.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:61:0) after 1m0s
[23:14:20.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:61:0)
[23:14:20.619] Timer tick, asking Buildbucket for the build status
[23:14:20.689] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:20.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:62:0) after 1m0s
[23:15:20.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:62:0)
[23:15:20.710] Timer tick, asking Buildbucket for the build status
[23:15:20.792] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:20.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:63:0) after 1m0s
[23:16:20.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:63:0)
[23:16:20.804] Timer tick, asking Buildbucket for the build status
[23:16:20.909] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:20.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:64:0) after 1m0s
[23:17:20.926] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:64:0)
[23:17:20.926] Timer tick, asking Buildbucket for the build status
[23:17:20.999] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:20.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:65:0) after 1m0s
[23:18:20.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:65:0)
[23:18:20.995] Timer tick, asking Buildbucket for the build status
[23:18:21.081] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:21.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:66:0) after 1m0s
[23:19:21.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:66:0)
[23:19:21.102] Timer tick, asking Buildbucket for the build status
[23:19:21.276] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:21.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:67:0) after 1m0s
[23:20:21.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:67:0)
[23:20:21.301] Timer tick, asking Buildbucket for the build status
[23:20:21.371] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:21.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:68:0) after 1m0s
[23:21:21.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:68:0)
[23:21:21.395] Timer tick, asking Buildbucket for the build status
[23:21:21.585] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:21.585] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:69:0) after 1m0s
[23:22:21.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:69:0)
[23:22:21.607] Timer tick, asking Buildbucket for the build status
[23:22:21.681] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:21.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:70:0) after 1m0s
[23:23:21.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:70:0)
[23:23:21.706] Timer tick, asking Buildbucket for the build status
[23:23:21.815] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:21.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:71:0) after 1m0s
[23:24:21.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:71:0)
[23:24:21.879] Timer tick, asking Buildbucket for the build status
[23:24:22.071] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:22.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:72:0) after 1m0s
[23:25:22.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:72:0)
[23:25:22.086] Timer tick, asking Buildbucket for the build status
[23:25:22.139] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:22.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:73:0) after 1m0s
[23:26:22.125] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:73:0)
[23:26:22.125] Timer tick, asking Buildbucket for the build status
[23:26:22.173] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:22.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:74:0) after 1m0s
[23:27:22.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:74:0)
[23:27:22.171] Timer tick, asking Buildbucket for the build status
[23:27:22.237] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:22.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:75:0) after 1m0s
[23:28:22.235] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:75:0)
[23:28:22.235] Timer tick, asking Buildbucket for the build status
[23:28:22.289] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:22.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:76:0) after 1m0s
[23:29:22.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:76:0)
[23:29:22.316] Timer tick, asking Buildbucket for the build status
[23:29:22.382] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:22.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:77:0) after 1m0s
[23:30:22.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:77:0)
[23:30:22.403] Timer tick, asking Buildbucket for the build status
[23:30:22.468] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:22.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:78:0) after 1m0s
[23:31:22.483] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:78:0)
[23:31:22.483] Timer tick, asking Buildbucket for the build status
[23:31:22.834] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:22.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:79:0) after 1m0s
[23:32:22.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:79:0)
[23:32:22.896] Timer tick, asking Buildbucket for the build status
[23:32:23.159] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:23.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:80:0) after 1m0s
[23:33:23.179] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:80:0)
[23:33:23.179] Timer tick, asking Buildbucket for the build status
[23:33:23.266] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:23.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:81:0) after 1m0s
[23:34:23.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:81:0)
[23:34:23.299] Timer tick, asking Buildbucket for the build status
[23:34:23.346] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:23.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:82:0) after 1m0s
[23:35:23.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:82:0)
[23:35:23.342] Timer tick, asking Buildbucket for the build status
[23:35:23.384] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:23.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:83:0) after 1m0s
[23:36:23.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:83:0)
[23:36:23.403] Timer tick, asking Buildbucket for the build status
[23:36:23.671] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:23.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:84:0) after 1m0s
[23:37:23.704] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:84:0)
[23:37:23.705] Timer tick, asking Buildbucket for the build status
[23:37:23.832] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:23.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:85:0) after 1m0s
[23:38:23.824] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:85:0)
[23:38:23.824] Timer tick, asking Buildbucket for the build status
[23:38:23.895] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:23.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:86:0) after 1m0s
[23:39:23.975] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:86:0)
[23:39:23.975] Timer tick, asking Buildbucket for the build status
[23:39:24.039] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:24.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:87:0) after 1m0s
[23:40:24.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:87:0)
[23:40:24.065] Timer tick, asking Buildbucket for the build status
[23:40:24.111] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:24.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:88:0) after 1m0s
[23:41:24.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:88:0)
[23:41:24.129] Timer tick, asking Buildbucket for the build status
[23:41:24.193] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:24.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:89:0) after 1m0s
[23:42:24.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:89:0)
[23:42:24.216] Timer tick, asking Buildbucket for the build status
[23:42:24.276] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:24.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:90:0) after 1m0s
[23:43:24.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:90:0)
[23:43:24.296] Timer tick, asking Buildbucket for the build status
[23:43:24.342] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:24.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:91:0) after 1m0s
[23:44:24.367] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:91:0)
[23:44:24.367] Timer tick, asking Buildbucket for the build status
[23:44:24.401] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:24.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:92:0) after 1m0s
[23:45:24.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:92:0)
[23:45:24.388] Timer tick, asking Buildbucket for the build status
[23:45:24.497] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:24.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:93:0) after 1m0s
[23:46:24.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:93:0)
[23:46:24.488] Timer tick, asking Buildbucket for the build status
[23:46:24.654] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:24.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:94:0) after 1m0s
[23:47:24.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:94:0)
[23:47:24.656] Timer tick, asking Buildbucket for the build status
[23:47:24.728] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:24.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:95:0) after 1m0s
[23:48:24.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:95:0)
[23:48:24.713] Timer tick, asking Buildbucket for the build status
[23:48:24.791] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:24.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:96:0) after 1m0s
[23:49:24.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:96:0)
[23:49:24.779] Timer tick, asking Buildbucket for the build status
[23:49:25.103] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:25.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:97:0) after 1m0s
[23:50:25.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:97:0)
[23:50:25.127] Timer tick, asking Buildbucket for the build status
[23:50:25.183] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:25.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:98:0) after 1m0s
[23:51:25.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:98:0)
[23:51:25.208] Timer tick, asking Buildbucket for the build status
[23:51:25.364] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:25.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:99:0) after 1m0s
[23:52:25.385] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:99:0)
[23:52:25.385] Timer tick, asking Buildbucket for the build status
[23:52:25.452] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:25.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:100:0) after 1m0s
[23:53:25.457] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:100:0)
[23:53:25.457] Timer tick, asking Buildbucket for the build status
[23:53:25.661] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:25.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:101:0) after 1m0s
[23:54:25.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:101:0)
[23:54:25.664] Timer tick, asking Buildbucket for the build status
[23:54:25.897] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:25.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:102:0) after 1m0s
[23:55:25.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:102:0)
[23:55:25.930] Timer tick, asking Buildbucket for the build status
[23:55:25.975] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:25.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:103:0) after 1m0s
[23:56:25.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:103:0)
[23:56:25.996] Timer tick, asking Buildbucket for the build status
[23:56:26.194] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:26.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:104:0) after 1m0s
[23:57:26.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:104:0)
[23:57:26.173] Timer tick, asking Buildbucket for the build status
[23:57:26.379] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:26.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:105:0) after 1m0s
[23:58:26.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:105:0)
[23:58:26.403] Timer tick, asking Buildbucket for the build status
[23:58:26.571] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:26.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:106:0) after 1m0s
[23:59:26.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:106:0)
[23:59:26.583] Timer tick, asking Buildbucket for the build status
[23:59:26.792] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:26.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:107:0) after 1m0s
[00:00:26.819] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:107:0)
[00:00:26.819] Timer tick, asking Buildbucket for the build status
[00:00:26.879] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:26.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:108:0) after 1m0s
[00:01:26.883] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:108:0)
[00:01:26.883] Timer tick, asking Buildbucket for the build status
[00:01:26.979] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:26.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:109:0) after 1m0s
[00:02:27.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:109:0)
[00:02:27.000] Timer tick, asking Buildbucket for the build status
[00:02:27.292] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:27.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:110:0) after 1m0s
[00:03:27.345] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:110:0)
[00:03:27.345] Timer tick, asking Buildbucket for the build status
[00:03:27.463] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:27.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:111:0) after 1m0s
[00:04:27.482] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:111:0)
[00:04:27.482] Timer tick, asking Buildbucket for the build status
[00:04:27.552] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:27.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:112:0) after 1m0s
[00:05:27.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:112:0)
[00:05:27.576] Timer tick, asking Buildbucket for the build status
[00:05:27.639] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:27.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:113:0) after 1m0s
[00:06:27.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:113:0)
[00:06:27.662] Timer tick, asking Buildbucket for the build status
[00:06:27.700] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:27.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:114:0) after 1m0s
[00:07:27.721] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:114:0)
[00:07:27.721] Timer tick, asking Buildbucket for the build status
[00:07:27.771] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:27.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:115:0) after 1m0s
[00:08:27.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:115:0)
[00:08:27.802] Timer tick, asking Buildbucket for the build status
[00:08:27.884] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:27.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:116:0) after 1m0s
[00:09:27.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:116:0)
[00:09:27.879] Timer tick, asking Buildbucket for the build status
[00:09:27.926] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:27.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:117:0) after 1m0s
[00:10:27.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:117:0)
[00:10:27.943] Timer tick, asking Buildbucket for the build status
[00:10:28.051] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:28.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:118:0) after 1m0s
[00:11:28.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:118:0)
[00:11:28.036] Timer tick, asking Buildbucket for the build status
[00:11:28.101] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:28.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:119:0) after 1m0s
[00:12:28.128] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:119:0)
[00:12:28.128] Timer tick, asking Buildbucket for the build status
[00:12:28.175] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:28.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:120:0) after 1m0s
[00:13:28.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:120:0)
[00:13:28.189] Timer tick, asking Buildbucket for the build status
[00:13:28.266] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:28.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:121:0) after 1m0s
[00:14:28.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:121:0)
[00:14:28.257] Timer tick, asking Buildbucket for the build status
[00:14:28.389] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:28.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:122:0) after 1m0s
[00:15:28.419] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:122:0)
[00:15:28.419] Timer tick, asking Buildbucket for the build status
[00:15:28.613] Build 8916436106240693984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:28.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9081866168152928416:123:0) after 1m0s
[00:15:40.026] Received PubSub notification, asking Buildbucket for the build status
[00:15:40.113] Build 8916436106240693984: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:15:40.113] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1555028139766971",
"created_by": "project:fuchsia",
"created_ts": "1555020913808289",
"failure_reason": "BUILD_FAILURE",
"id": "8916436106240693984",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"57c3d4bed3ee625e548154063e9f6e7ea70d6ed4\"}}",
"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-c-chxr\", \"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\": \"57c3d4bed3ee625e548154063e9f6e7ea70d6ed4\", \"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-c-chxr\"], \"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\": [\"4243-f38882f\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1555020916047466",
"status": "COMPLETED",
"status_changed_ts": "1555020916734655",
"tags": [
"builder:clang",
"buildset:commit/git/57c3d4bed3ee625e548154063e9f6e7ea70d6ed4",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/57c3d4bed3ee625e548154063e9f6e7ea70d6ed4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081866168152928416",
"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/8916436106240693984/+/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:442749a86eec6310",
"user_agent:luci-scheduler"
],
"updated_ts": "1555028139816516",
"url": "https://ci.chromium.org/b/8916436106240693984",
"utcnow_ts": "1555028140093628"
}
}
[00:15:40.113] Invocation finished in 2h0m27.512923349s with status FAILED