[22:54:10.104] New invocation is queued and will start shortly
[22:54:11.268] Starting the invocation (attempt 1)
[22:54:11.305] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:54:11.305] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:54:11.305] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9084944013982876368",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"ce3be45cacc168194bb7d5e84de365548e1c49a2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9084944013982876368",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/ce3be45cacc168194bb7d5e84de365548e1c49a2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ce3be45cacc168194bb7d5e84de365548e1c49a2",
"gitiles_ref:refs/heads/master"
]
}
[22:54:12.439] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552085651385209",
"id": "8919513951971275888",
"lease_expiration_ts": "1552211652071083",
"lease_key": "564065995",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"ce3be45cacc168194bb7d5e84de365548e1c49a2\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552085652246884",
"tags": [
"builder:clang",
"buildset:commit/git/ce3be45cacc168194bb7d5e84de365548e1c49a2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ce3be45cacc168194bb7d5e84de365548e1c49a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084944013982876368",
"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/8919513951971275888/+/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:4378551957719b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552085652246993",
"url": "https://ci.chromium.org/b/8919513951971275888",
"utcnow_ts": "1552085652430021"
}
}
[22:54:12.439] Task URL: https://ci.chromium.org/b/8919513951971275888
[22:54:12.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:2:0) after 1m0s
[22:54:28.099] Received PubSub notification, asking Buildbucket for the build status
[22:54:28.148] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:12.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:2:0)
[22:55:12.464] Timer tick, asking Buildbucket for the build status
[22:55:12.600] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:12.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:4:0) after 1m0s
[22:56:12.636] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:4:0)
[22:56:12.636] Timer tick, asking Buildbucket for the build status
[22:56:12.884] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:12.884] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:5:0) after 1m0s
[22:57:12.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:5:0)
[22:57:12.887] Timer tick, asking Buildbucket for the build status
[22:57:12.945] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:12.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:6:0) after 1m0s
[22:58:12.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:6:0)
[22:58:12.934] Timer tick, asking Buildbucket for the build status
[22:58:13.070] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:13.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:7:0) after 1m0s
[22:59:13.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:7:0)
[22:59:13.088] Timer tick, asking Buildbucket for the build status
[22:59:13.185] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:13.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:8:0) after 1m0s
[23:00:13.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:8:0)
[23:00:13.169] Timer tick, asking Buildbucket for the build status
[23:00:13.322] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:13.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:9:0) after 1m0s
[23:01:13.291] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:9:0)
[23:01:13.291] Timer tick, asking Buildbucket for the build status
[23:01:13.397] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:13.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:10:0) after 1m0s
[23:02:13.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:10:0)
[23:02:13.416] Timer tick, asking Buildbucket for the build status
[23:02:13.460] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:13.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:11:0) after 1m0s
[23:03:13.467] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:11:0)
[23:03:13.467] Timer tick, asking Buildbucket for the build status
[23:03:13.652] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:13.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:12:0) after 1m0s
[23:04:13.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:12:0)
[23:04:13.662] Timer tick, asking Buildbucket for the build status
[23:04:13.710] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:13.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:13:0) after 1m0s
[23:05:13.730] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:13:0)
[23:05:13.730] Timer tick, asking Buildbucket for the build status
[23:05:13.798] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:13.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:14:0) after 1m0s
[23:06:13.797] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:14:0)
[23:06:13.797] Timer tick, asking Buildbucket for the build status
[23:06:13.841] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:13.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:15:0) after 1m0s
[23:07:13.858] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:15:0)
[23:07:13.858] Timer tick, asking Buildbucket for the build status
[23:07:13.912] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:13.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:16:0) after 1m0s
[23:08:13.925] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:16:0)
[23:08:13.925] Timer tick, asking Buildbucket for the build status
[23:08:13.989] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:13.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:17:0) after 1m0s
[23:09:13.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:17:0)
[23:09:13.997] Timer tick, asking Buildbucket for the build status
[23:09:14.037] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:14.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:18:0) after 1m0s
[23:10:14.056] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:18:0)
[23:10:14.056] Timer tick, asking Buildbucket for the build status
[23:10:14.178] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:14.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:19:0) after 1m0s
[23:11:14.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:19:0)
[23:11:14.189] Timer tick, asking Buildbucket for the build status
[23:11:14.362] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:14.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:20:0) after 1m0s
[23:12:14.414] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:20:0)
[23:12:14.414] Timer tick, asking Buildbucket for the build status
[23:12:14.548] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:14.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:21:0) after 1m0s
[23:13:14.586] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:21:0)
[23:13:14.586] Timer tick, asking Buildbucket for the build status
[23:13:14.743] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:14.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:22:0) after 1m0s
[23:14:14.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:22:0)
[23:14:14.765] Timer tick, asking Buildbucket for the build status
[23:14:14.824] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:14.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:23:0) after 1m0s
[23:15:14.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:23:0)
[23:15:14.803] Timer tick, asking Buildbucket for the build status
[23:15:14.846] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:14.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:24:0) after 1m0s
[23:16:14.839] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:24:0)
[23:16:14.839] Timer tick, asking Buildbucket for the build status
[23:16:14.910] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:14.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:25:0) after 1m0s
[23:17:14.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:25:0)
[23:17:14.911] Timer tick, asking Buildbucket for the build status
[23:17:14.963] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:14.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:26:0) after 1m0s
[23:18:14.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:26:0)
[23:18:14.967] Timer tick, asking Buildbucket for the build status
[23:18:15.031] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:15.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:27:0) after 1m0s
[23:19:15.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:27:0)
[23:19:15.053] Timer tick, asking Buildbucket for the build status
[23:19:15.103] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:15.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:28:0) after 1m0s
[23:20:15.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:28:0)
[23:20:15.123] Timer tick, asking Buildbucket for the build status
[23:20:15.252] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:15.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:29:0) after 1m0s
[23:21:15.238] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:29:0)
[23:21:15.238] Timer tick, asking Buildbucket for the build status
[23:21:15.574] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:15.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:30:0) after 1m0s
[23:22:15.588] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:30:0)
[23:22:15.588] Timer tick, asking Buildbucket for the build status
[23:22:15.634] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:15.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:31:0) after 1m0s
[23:23:15.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:31:0)
[23:23:15.648] Timer tick, asking Buildbucket for the build status
[23:23:15.787] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:15.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:32:0) after 1m0s
[23:24:15.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:32:0)
[23:24:15.806] Timer tick, asking Buildbucket for the build status
[23:24:15.882] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:15.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:33:0) after 1m0s
[23:25:15.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:33:0)
[23:25:15.884] Timer tick, asking Buildbucket for the build status
[23:25:15.985] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:15.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:34:0) after 1m0s
[23:26:15.979] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:34:0)
[23:26:15.979] Timer tick, asking Buildbucket for the build status
[23:26:16.037] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:16.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:35:0) after 1m0s
[23:27:16.055] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:35:0)
[23:27:16.055] Timer tick, asking Buildbucket for the build status
[23:27:16.130] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:16.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:36:0) after 1m0s
[23:28:16.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:36:0)
[23:28:16.114] Timer tick, asking Buildbucket for the build status
[23:28:16.237] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:16.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:37:0) after 1m0s
[23:29:16.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:37:0)
[23:29:16.212] Timer tick, asking Buildbucket for the build status
[23:29:16.391] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:16.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:38:0) after 1m0s
[23:30:16.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:38:0)
[23:30:16.428] Timer tick, asking Buildbucket for the build status
[23:30:16.513] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:16.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:39:0) after 1m0s
[23:31:16.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:39:0)
[23:31:16.551] Timer tick, asking Buildbucket for the build status
[23:31:16.646] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:16.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:40:0) after 1m0s
[23:32:16.647] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:40:0)
[23:32:16.647] Timer tick, asking Buildbucket for the build status
[23:32:16.713] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:16.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:41:0) after 1m0s
[23:33:16.733] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:41:0)
[23:33:16.733] Timer tick, asking Buildbucket for the build status
[23:33:16.881] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:16.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:42:0) after 1m0s
[23:34:16.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:42:0)
[23:34:16.909] Timer tick, asking Buildbucket for the build status
[23:34:17.103] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:17.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:43:0) after 1m0s
[23:35:17.082] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:43:0)
[23:35:17.082] Timer tick, asking Buildbucket for the build status
[23:35:17.130] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:17.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:44:0) after 1m0s
[23:36:17.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:44:0)
[23:36:17.102] Timer tick, asking Buildbucket for the build status
[23:36:17.197] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:17.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:45:0) after 1m0s
[23:37:17.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:45:0)
[23:37:17.209] Timer tick, asking Buildbucket for the build status
[23:37:17.301] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:17.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:46:0) after 1m0s
[23:38:17.311] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:46:0)
[23:38:17.311] Timer tick, asking Buildbucket for the build status
[23:38:17.357] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:17.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:47:0) after 1m0s
[23:39:17.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:47:0)
[23:39:17.332] Timer tick, asking Buildbucket for the build status
[23:39:17.476] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:17.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:48:0) after 1m0s
[23:40:17.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:48:0)
[23:40:17.497] Timer tick, asking Buildbucket for the build status
[23:40:17.546] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:17.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:49:0) after 1m0s
[23:41:17.566] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:49:0)
[23:41:17.566] Timer tick, asking Buildbucket for the build status
[23:41:17.721] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:17.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:50:0) after 1m0s
[23:42:17.760] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:50:0)
[23:42:17.760] Timer tick, asking Buildbucket for the build status
[23:42:17.859] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:17.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:51:0) after 1m0s
[23:43:17.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:51:0)
[23:43:17.880] Timer tick, asking Buildbucket for the build status
[23:43:17.938] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:17.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:52:0) after 1m0s
[23:44:17.961] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:52:0)
[23:44:17.961] Timer tick, asking Buildbucket for the build status
[23:44:18.014] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:18.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:53:0) after 1m0s
[23:45:18.074] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:53:0)
[23:45:18.074] Timer tick, asking Buildbucket for the build status
[23:45:18.184] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:18.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:54:0) after 1m0s
[23:46:18.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:54:0)
[23:46:18.193] Timer tick, asking Buildbucket for the build status
[23:46:18.368] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:18.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:55:0) after 1m0s
[23:47:18.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:55:0)
[23:47:18.395] Timer tick, asking Buildbucket for the build status
[23:47:18.445] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:18.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:56:0) after 1m0s
[23:48:18.437] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:56:0)
[23:48:18.437] Timer tick, asking Buildbucket for the build status
[23:48:18.500] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:18.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:57:0) after 1m0s
[23:49:18.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:57:0)
[23:49:18.521] Timer tick, asking Buildbucket for the build status
[23:49:18.610] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:18.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:58:0) after 1m0s
[23:50:18.635] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:58:0)
[23:50:18.635] Timer tick, asking Buildbucket for the build status
[23:50:18.722] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:18.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:59:0) after 1m0s
[23:51:18.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:59:0)
[23:51:18.719] Timer tick, asking Buildbucket for the build status
[23:51:18.788] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:18.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:60:0) after 1m0s
[23:52:18.808] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:60:0)
[23:52:18.808] Timer tick, asking Buildbucket for the build status
[23:52:18.852] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:18.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:61:0) after 1m0s
[23:53:18.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:61:0)
[23:53:18.873] Timer tick, asking Buildbucket for the build status
[23:53:18.917] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:18.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:62:0) after 1m0s
[23:54:18.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:62:0)
[23:54:18.938] Timer tick, asking Buildbucket for the build status
[23:54:18.982] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:18.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:63:0) after 1m0s
[23:55:19.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:63:0)
[23:55:19.002] Timer tick, asking Buildbucket for the build status
[23:55:19.070] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:19.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:64:0) after 1m0s
[23:56:19.095] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:64:0)
[23:56:19.095] Timer tick, asking Buildbucket for the build status
[23:56:19.217] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:19.217] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:65:0) after 1m0s
[23:57:19.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:65:0)
[23:57:19.204] Timer tick, asking Buildbucket for the build status
[23:57:19.252] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:19.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:66:0) after 1m0s
[23:58:19.357] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:66:0)
[23:58:19.357] Timer tick, asking Buildbucket for the build status
[23:58:19.498] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:19.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:67:0) after 1m0s
[23:59:19.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:67:0)
[23:59:19.499] Timer tick, asking Buildbucket for the build status
[23:59:19.571] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:19.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:68:0) after 1m0s
[00:00:19.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:68:0)
[00:00:19.594] Timer tick, asking Buildbucket for the build status
[00:00:19.639] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:19.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:69:0) after 1m0s
[00:01:19.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:69:0)
[00:01:19.661] Timer tick, asking Buildbucket for the build status
[00:01:19.906] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:19.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:70:0) after 1m0s
[00:02:19.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:70:0)
[00:02:19.927] Timer tick, asking Buildbucket for the build status
[00:02:20.070] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:20.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:71:0) after 1m0s
[00:03:20.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:71:0)
[00:03:20.094] Timer tick, asking Buildbucket for the build status
[00:03:20.323] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:20.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:72:0) after 1m0s
[00:04:20.344] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:72:0)
[00:04:20.344] Timer tick, asking Buildbucket for the build status
[00:04:20.461] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:20.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:73:0) after 1m0s
[00:05:20.442] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:73:0)
[00:05:20.442] Timer tick, asking Buildbucket for the build status
[00:05:20.535] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:20.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:74:0) after 1m0s
[00:06:20.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:74:0)
[00:06:20.571] Timer tick, asking Buildbucket for the build status
[00:06:20.635] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:20.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:75:0) after 1m0s
[00:07:20.657] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:75:0)
[00:07:20.657] Timer tick, asking Buildbucket for the build status
[00:07:21.005] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:21.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:76:0) after 1m0s
[00:08:21.010] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:76:0)
[00:08:21.010] Timer tick, asking Buildbucket for the build status
[00:08:21.071] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:21.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:77:0) after 1m0s
[00:09:21.186] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:77:0)
[00:09:21.186] Timer tick, asking Buildbucket for the build status
[00:09:21.388] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:21.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:78:0) after 1m0s
[00:10:21.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:78:0)
[00:10:21.386] Timer tick, asking Buildbucket for the build status
[00:10:21.533] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:21.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:79:0) after 1m0s
[00:11:21.556] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:79:0)
[00:11:21.556] Timer tick, asking Buildbucket for the build status
[00:11:21.686] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:21.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:80:0) after 1m0s
[00:12:21.709] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:80:0)
[00:12:21.709] Timer tick, asking Buildbucket for the build status
[00:12:21.760] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:21.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:81:0) after 1m0s
[00:13:21.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:81:0)
[00:13:21.766] Timer tick, asking Buildbucket for the build status
[00:13:21.820] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:21.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:82:0) after 1m0s
[00:14:21.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:82:0)
[00:14:21.836] Timer tick, asking Buildbucket for the build status
[00:14:21.894] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:21.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:83:0) after 1m0s
[00:15:21.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:83:0)
[00:15:21.896] Timer tick, asking Buildbucket for the build status
[00:15:21.980] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:21.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:84:0) after 1m0s
[00:16:22.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:84:0)
[00:16:22.008] Timer tick, asking Buildbucket for the build status
[00:16:22.191] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:22.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:85:0) after 1m0s
[00:17:22.195] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:85:0)
[00:17:22.195] Timer tick, asking Buildbucket for the build status
[00:17:22.360] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:22.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:86:0) after 1m0s
[00:18:22.381] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:86:0)
[00:18:22.381] Timer tick, asking Buildbucket for the build status
[00:18:22.580] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:22.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:87:0) after 1m0s
[00:19:22.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:87:0)
[00:19:22.605] Timer tick, asking Buildbucket for the build status
[00:19:22.657] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:22.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:88:0) after 1m0s
[00:20:22.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:88:0)
[00:20:22.727] Timer tick, asking Buildbucket for the build status
[00:20:22.791] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:22.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:89:0) after 1m0s
[00:21:22.797] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:89:0)
[00:21:22.797] Timer tick, asking Buildbucket for the build status
[00:21:22.916] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:22.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:90:0) after 1m0s
[00:22:22.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:90:0)
[00:22:22.909] Timer tick, asking Buildbucket for the build status
[00:22:22.969] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:22.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:91:0) after 1m0s
[00:23:23.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:91:0)
[00:23:23.008] Timer tick, asking Buildbucket for the build status
[00:23:23.086] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:23.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:92:0) after 1m0s
[00:24:23.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:92:0)
[00:24:23.088] Timer tick, asking Buildbucket for the build status
[00:24:23.134] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:23.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:93:0) after 1m0s
[00:25:23.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:93:0)
[00:25:23.156] Timer tick, asking Buildbucket for the build status
[00:25:23.488] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:23.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:94:0) after 1m0s
[00:26:23.501] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:94:0)
[00:26:23.501] Timer tick, asking Buildbucket for the build status
[00:26:23.627] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:23.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:95:0) after 1m0s
[00:27:23.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:95:0)
[00:27:23.625] Timer tick, asking Buildbucket for the build status
[00:27:23.936] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:23.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:96:0) after 1m0s
[00:28:23.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:96:0)
[00:28:23.922] Timer tick, asking Buildbucket for the build status
[00:28:23.995] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:23.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:97:0) after 1m0s
[00:29:24.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:97:0)
[00:29:24.005] Timer tick, asking Buildbucket for the build status
[00:29:24.248] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:24.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:98:0) after 1m0s
[00:30:24.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:98:0)
[00:30:24.296] Timer tick, asking Buildbucket for the build status
[00:30:24.467] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:24.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:99:0) after 1m0s
[00:31:24.492] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:99:0)
[00:31:24.492] Timer tick, asking Buildbucket for the build status
[00:31:24.536] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:24.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:100:0) after 1m0s
[00:32:24.524] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:100:0)
[00:32:24.524] Timer tick, asking Buildbucket for the build status
[00:32:24.592] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:24.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:101:0) after 1m0s
[00:33:24.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:101:0)
[00:33:24.611] Timer tick, asking Buildbucket for the build status
[00:33:24.660] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:24.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:102:0) after 1m0s
[00:34:24.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:102:0)
[00:34:24.692] Timer tick, asking Buildbucket for the build status
[00:34:25.032] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:25.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:103:0) after 1m0s
[00:35:25.050] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:103:0)
[00:35:25.050] Timer tick, asking Buildbucket for the build status
[00:35:25.120] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:25.120] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:104:0) after 1m0s
[00:36:25.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:104:0)
[00:36:25.183] Timer tick, asking Buildbucket for the build status
[00:36:25.294] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:25.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:105:0) after 1m0s
[00:37:25.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:105:0)
[00:37:25.316] Timer tick, asking Buildbucket for the build status
[00:37:25.785] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:25.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:106:0) after 1m0s
[00:38:25.814] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:106:0)
[00:38:25.814] Timer tick, asking Buildbucket for the build status
[00:38:25.976] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:25.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:107:0) after 1m0s
[00:39:25.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:107:0)
[00:39:25.997] Timer tick, asking Buildbucket for the build status
[00:39:26.195] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:26.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:108:0) after 1m0s
[00:40:26.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:108:0)
[00:40:26.188] Timer tick, asking Buildbucket for the build status
[00:40:26.232] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:26.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:109:0) after 1m0s
[00:41:26.234] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:109:0)
[00:41:26.234] Timer tick, asking Buildbucket for the build status
[00:41:26.301] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:26.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:110:0) after 1m0s
[00:42:26.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:110:0)
[00:42:26.323] Timer tick, asking Buildbucket for the build status
[00:42:26.393] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:26.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:111:0) after 1m0s
[00:43:26.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:111:0)
[00:43:26.416] Timer tick, asking Buildbucket for the build status
[00:43:26.631] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:26.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:112:0) after 1m0s
[00:44:26.638] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:112:0)
[00:44:26.638] Timer tick, asking Buildbucket for the build status
[00:44:26.701] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:26.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:113:0) after 1m0s
[00:45:26.713] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:113:0)
[00:45:26.713] Timer tick, asking Buildbucket for the build status
[00:45:26.991] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:26.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:114:0) after 1m0s
[00:46:26.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:114:0)
[00:46:26.983] Timer tick, asking Buildbucket for the build status
[00:46:27.155] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:27.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:115:0) after 1m0s
[00:47:27.135] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:115:0)
[00:47:27.135] Timer tick, asking Buildbucket for the build status
[00:47:27.192] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:27.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:116:0) after 1m0s
[00:48:27.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:116:0)
[00:48:27.166] Timer tick, asking Buildbucket for the build status
[00:48:27.224] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:27.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:117:0) after 1m0s
[00:49:27.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:117:0)
[00:49:27.274] Timer tick, asking Buildbucket for the build status
[00:49:27.493] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:27.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:118:0) after 1m0s
[00:50:27.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:118:0)
[00:50:27.518] Timer tick, asking Buildbucket for the build status
[00:50:27.614] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:27.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:119:0) after 1m0s
[00:51:27.592] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:119:0)
[00:51:27.592] Timer tick, asking Buildbucket for the build status
[00:51:27.677] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:27.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:120:0) after 1m0s
[00:52:27.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:120:0)
[00:52:27.656] Timer tick, asking Buildbucket for the build status
[00:52:27.775] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:27.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:121:0) after 1m0s
[00:53:27.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:121:0)
[00:53:27.810] Timer tick, asking Buildbucket for the build status
[00:53:27.888] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:27.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:122:0) after 1m0s
[00:54:27.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:122:0)
[00:54:27.918] Timer tick, asking Buildbucket for the build status
[00:54:27.973] Build 8919513951971275888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:27.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9084944013982876368:123:0) after 1m0s
[00:54:59.601] Received PubSub notification, asking Buildbucket for the build status
[00:54:59.651] Build 8919513951971275888: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:54:59.651] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1552092898453713",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552085651385209",
"failure_reason": "BUILD_FAILURE",
"id": "8919513951971275888",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"ce3be45cacc168194bb7d5e84de365548e1c49a2\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"image\": [\"fuchsia-debian-9-drawfork-v20181107-ee01d6c\"], \"kvm\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4158-1053c58\"], \"machine_type\": [\"n1-standard-1\"], \"gpu\": [\"none\"], \"cores\": [\"1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"id\": [\"fuchsia-debian-1-03c1d9de-us-central1-c-bkkk\"], \"pool\": [\"luci.fuchsia.prod\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"Failure buildbucket.run\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"recipe\": \"buildbucket_trigger\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"timeout\": 7200.0, \"path_config\": \"generic\", \"revision\": \"ce3be45cacc168194bb7d5e84de365548e1c49a2\", \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-03c1d9de-us-central1-c-bkkk\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1552085667370311",
"status": "COMPLETED",
"status_changed_ts": "1552092899097479",
"tags": [
"builder:clang",
"buildset:commit/git/ce3be45cacc168194bb7d5e84de365548e1c49a2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/ce3be45cacc168194bb7d5e84de365548e1c49a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084944013982876368",
"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/8919513951971275888/+/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:4378551957719b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552092899113382",
"url": "https://ci.chromium.org/b/8919513951971275888",
"utcnow_ts": "1552092899643925"
}
}
[00:54:59.651] Invocation finished in 2h0m49.568074122s with status FAILED