[22:57:18.340] New invocation is queued and will start shortly
[22:57:19.403] Starting the invocation (attempt 1)
[22:57:19.440] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:57:19.440] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:57:19.440] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079326804681852560",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"3478494c1fdcbbee3e22895dcd6e6919d6c05430\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079326804681852560",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/3478494c1fdcbbee3e22895dcd6e6919d6c05430",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3478494c1fdcbbee3e22895dcd6e6919d6c05430",
"gitiles_ref:refs/heads/master"
]
}
[22:57:21.195] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557442639651281",
"id": "8913896742644080144",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"3478494c1fdcbbee3e22895dcd6e6919d6c05430\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557442640524410",
"tags": [
"builder:clang",
"buildset:commit/git/3478494c1fdcbbee3e22895dcd6e6919d6c05430",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3478494c1fdcbbee3e22895dcd6e6919d6c05430",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079326804681852560",
"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/8913896742644080144/+/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": "1557442640524519",
"url": "https://ci.chromium.org/b/8913896742644080144",
"utcnow_ts": "1557442641019480"
}
}
[22:57:21.195] Task URL: https://ci.chromium.org/b/8913896742644080144
[22:57:21.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:2:0) after 1m0s
[22:57:39.840] Received PubSub notification, asking Buildbucket for the build status
[22:57:40.925] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:21.345] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:2:0)
[22:58:21.345] Timer tick, asking Buildbucket for the build status
[22:58:21.525] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:21.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:4:0) after 1m0s
[22:59:21.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:4:0)
[22:59:21.513] Timer tick, asking Buildbucket for the build status
[22:59:21.620] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:21.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:5:0) after 1m0s
[23:00:22.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:5:0)
[23:00:22.031] Timer tick, asking Buildbucket for the build status
[23:00:22.233] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:22.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:6:0) after 1m0s
[23:01:22.270] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:6:0)
[23:01:22.270] Timer tick, asking Buildbucket for the build status
[23:01:22.628] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:22.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:7:0) after 1m0s
[23:02:22.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:7:0)
[23:02:22.606] Timer tick, asking Buildbucket for the build status
[23:02:22.757] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:22.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:8:0) after 1m0s
[23:03:22.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:8:0)
[23:03:22.776] Timer tick, asking Buildbucket for the build status
[23:03:22.843] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:22.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:9:0) after 1m0s
[23:04:22.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:9:0)
[23:04:22.833] Timer tick, asking Buildbucket for the build status
[23:04:22.928] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:22.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:10:0) after 1m0s
[23:05:22.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:10:0)
[23:05:22.902] Timer tick, asking Buildbucket for the build status
[23:05:22.997] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:22.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:11:0) after 1m0s
[23:06:44.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:11:0)
[23:06:44.993] Timer tick, asking Buildbucket for the build status
[23:06:45.087] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:45.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:12:0) after 1m0s
[23:07:59.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:12:0)
[23:07:59.810] Timer tick, asking Buildbucket for the build status
[23:08:00.389] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:00.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:13:0) after 1m0s
[23:09:01.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:13:0)
[23:09:01.776] Timer tick, asking Buildbucket for the build status
[23:09:03.320] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:03.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:14:0) after 1m0s
[23:10:03.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:14:0)
[23:10:03.405] Timer tick, asking Buildbucket for the build status
[23:10:05.680] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:05.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:15:0) after 1m0s
[23:11:05.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:15:0)
[23:11:05.725] Timer tick, asking Buildbucket for the build status
[23:11:05.954] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:05.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:16:0) after 1m0s
[23:12:06.000] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:16:0)
[23:12:06.000] Timer tick, asking Buildbucket for the build status
[23:12:06.156] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:06.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:17:0) after 1m0s
[23:13:06.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:17:0)
[23:13:06.191] Timer tick, asking Buildbucket for the build status
[23:13:07.203] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:07.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:18:0) after 1m0s
[23:14:07.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:18:0)
[23:14:07.214] Timer tick, asking Buildbucket for the build status
[23:14:07.316] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:07.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:19:0) after 1m0s
[23:15:07.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:19:0)
[23:15:07.346] Timer tick, asking Buildbucket for the build status
[23:15:07.404] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:07.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:20:0) after 1m0s
[23:16:07.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:20:0)
[23:16:07.439] Timer tick, asking Buildbucket for the build status
[23:16:07.803] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:07.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:21:0) after 1m0s
[23:17:07.841] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:21:0)
[23:17:07.841] Timer tick, asking Buildbucket for the build status
[23:17:08.222] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:08.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:22:0) after 1m0s
[23:18:08.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:22:0)
[23:18:08.274] Timer tick, asking Buildbucket for the build status
[23:18:08.371] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:08.371] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:23:0) after 1m0s
[23:19:08.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:23:0)
[23:19:08.374] Timer tick, asking Buildbucket for the build status
[23:19:08.872] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:08.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:24:0) after 1m0s
[23:20:08.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:24:0)
[23:20:08.933] Timer tick, asking Buildbucket for the build status
[23:20:09.027] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:09.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:25:0) after 1m0s
[23:21:09.065] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:25:0)
[23:21:09.066] Timer tick, asking Buildbucket for the build status
[23:21:09.533] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:09.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:26:0) after 1m0s
[23:22:09.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:26:0)
[23:22:09.569] Timer tick, asking Buildbucket for the build status
[23:22:09.635] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:09.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:27:0) after 1m0s
[23:23:09.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:27:0)
[23:23:09.795] Timer tick, asking Buildbucket for the build status
[23:23:10.393] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:10.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:28:0) after 1m0s
[23:24:10.558] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:28:0)
[23:24:10.558] Timer tick, asking Buildbucket for the build status
[23:24:10.746] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:10.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:29:0) after 1m0s
[23:25:10.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:29:0)
[23:25:10.757] Timer tick, asking Buildbucket for the build status
[23:25:10.970] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:10.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:30:0) after 1m0s
[23:26:11.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:30:0)
[23:26:11.282] Timer tick, asking Buildbucket for the build status
[23:26:11.503] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:11.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:31:0) after 1m0s
[23:27:11.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:31:0)
[23:27:11.676] Timer tick, asking Buildbucket for the build status
[23:27:12.347] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:12.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:32:0) after 1m0s
[23:28:12.565] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:32:0)
[23:28:12.565] Timer tick, asking Buildbucket for the build status
[23:28:12.670] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:12.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:33:0) after 1m0s
[23:29:12.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:33:0)
[23:29:12.959] Timer tick, asking Buildbucket for the build status
[23:29:13.227] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:13.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:34:0) after 1m0s
[23:30:13.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:34:0)
[23:30:13.395] Timer tick, asking Buildbucket for the build status
[23:30:13.523] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:13.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:35:0) after 1m0s
[23:31:13.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:35:0)
[23:31:13.938] Timer tick, asking Buildbucket for the build status
[23:31:13.987] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:13.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:36:0) after 1m0s
[23:32:14.076] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:36:0)
[23:32:14.076] Timer tick, asking Buildbucket for the build status
[23:32:14.126] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:14.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:37:0) after 1m0s
[23:33:14.570] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:37:0)
[23:33:14.570] Timer tick, asking Buildbucket for the build status
[23:33:14.693] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:14.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:38:0) after 1m0s
[23:34:15.216] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:38:0)
[23:34:15.216] Timer tick, asking Buildbucket for the build status
[23:34:15.309] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:15.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:39:0) after 1m0s
[23:35:15.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:39:0)
[23:35:15.420] Timer tick, asking Buildbucket for the build status
[23:35:15.531] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:15.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:40:0) after 1m0s
[23:36:15.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:40:0)
[23:36:15.534] Timer tick, asking Buildbucket for the build status
[23:36:15.668] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:15.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:41:0) after 1m0s
[23:37:15.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:41:0)
[23:37:15.717] Timer tick, asking Buildbucket for the build status
[23:37:15.763] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:15.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:42:0) after 1m0s
[23:38:15.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:42:0)
[23:38:15.795] Timer tick, asking Buildbucket for the build status
[23:38:15.843] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:15.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:43:0) after 1m0s
[23:39:15.866] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:43:0)
[23:39:15.866] Timer tick, asking Buildbucket for the build status
[23:39:15.997] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:15.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:44:0) after 1m0s
[23:40:16.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:44:0)
[23:40:16.028] Timer tick, asking Buildbucket for the build status
[23:40:16.344] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:16.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:45:0) after 1m0s
[23:41:16.353] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:45:0)
[23:41:16.353] Timer tick, asking Buildbucket for the build status
[23:41:16.571] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:16.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:46:0) after 1m0s
[23:42:16.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:46:0)
[23:42:16.594] Timer tick, asking Buildbucket for the build status
[23:42:16.929] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:16.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:47:0) after 1m0s
[23:43:16.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:47:0)
[23:43:16.949] Timer tick, asking Buildbucket for the build status
[23:43:17.114] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:17.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:48:0) after 1m0s
[23:44:17.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:48:0)
[23:44:17.138] Timer tick, asking Buildbucket for the build status
[23:44:17.209] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:17.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:49:0) after 1m0s
[23:45:17.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:49:0)
[23:45:17.210] Timer tick, asking Buildbucket for the build status
[23:45:17.450] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:17.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:50:0) after 1m0s
[23:46:17.444] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:50:0)
[23:46:17.444] Timer tick, asking Buildbucket for the build status
[23:46:17.747] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:17.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:51:0) after 1m0s
[23:47:18.057] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:51:0)
[23:47:18.057] Timer tick, asking Buildbucket for the build status
[23:47:18.355] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:18.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:52:0) after 1m0s
[23:48:18.871] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:52:0)
[23:48:18.871] Timer tick, asking Buildbucket for the build status
[23:48:18.920] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:18.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:53:0) after 1m0s
[23:49:19.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:53:0)
[23:49:19.104] Timer tick, asking Buildbucket for the build status
[23:49:19.151] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:19.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:54:0) after 1m0s
[23:50:19.138] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:54:0)
[23:50:19.138] Timer tick, asking Buildbucket for the build status
[23:50:19.261] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:19.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:55:0) after 1m0s
[23:51:19.319] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:55:0)
[23:51:19.319] Timer tick, asking Buildbucket for the build status
[23:51:19.685] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:19.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:56:0) after 1m0s
[23:52:19.855] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:56:0)
[23:52:19.855] Timer tick, asking Buildbucket for the build status
[23:52:19.963] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:19.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:57:0) after 1m0s
[23:53:20.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:57:0)
[23:53:20.088] Timer tick, asking Buildbucket for the build status
[23:53:20.304] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:20.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:58:0) after 1m0s
[23:54:20.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:58:0)
[23:54:20.296] Timer tick, asking Buildbucket for the build status
[23:54:22.190] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:22.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:59:0) after 1m0s
[23:55:22.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:59:0)
[23:55:22.223] Timer tick, asking Buildbucket for the build status
[23:55:22.427] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:22.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:60:0) after 1m0s
[23:56:22.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:60:0)
[23:56:22.427] Timer tick, asking Buildbucket for the build status
[23:56:22.526] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:22.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:61:0) after 1m0s
[23:57:22.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:61:0)
[23:57:22.577] Timer tick, asking Buildbucket for the build status
[23:57:22.646] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:22.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:62:0) after 1m0s
[23:58:22.824] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:62:0)
[23:58:22.824] Timer tick, asking Buildbucket for the build status
[23:58:22.861] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:22.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:63:0) after 1m0s
[23:59:22.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:63:0)
[23:59:22.908] Timer tick, asking Buildbucket for the build status
[23:59:23.265] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:23.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:64:0) after 1m0s
[00:00:23.244] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:64:0)
[00:00:23.244] Timer tick, asking Buildbucket for the build status
[00:00:23.437] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:23.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:65:0) after 1m0s
[00:01:23.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:65:0)
[00:01:23.463] Timer tick, asking Buildbucket for the build status
[00:01:23.503] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:23.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:66:0) after 1m0s
[00:02:23.543] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:66:0)
[00:02:23.543] Timer tick, asking Buildbucket for the build status
[00:02:23.600] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:23.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:67:0) after 1m0s
[00:03:23.653] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:67:0)
[00:03:23.653] Timer tick, asking Buildbucket for the build status
[00:03:23.703] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:23.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:68:0) after 1m0s
[00:04:23.847] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:68:0)
[00:04:23.848] Timer tick, asking Buildbucket for the build status
[00:04:24.310] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:24.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:69:0) after 1m0s
[00:05:24.486] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:69:0)
[00:05:24.486] Timer tick, asking Buildbucket for the build status
[00:05:24.754] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:24.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:70:0) after 1m0s
[00:06:24.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:70:0)
[00:06:24.745] Timer tick, asking Buildbucket for the build status
[00:06:25.320] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:25.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:71:0) after 1m0s
[00:07:25.343] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:71:0)
[00:07:25.343] Timer tick, asking Buildbucket for the build status
[00:07:26.143] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:26.143] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:72:0) after 1m0s
[00:08:27.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:72:0)
[00:08:27.688] Timer tick, asking Buildbucket for the build status
[00:08:27.770] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:27.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:73:0) after 1m0s
[00:09:27.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:73:0)
[00:09:27.792] Timer tick, asking Buildbucket for the build status
[00:09:27.928] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:27.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:74:0) after 1m0s
[00:10:27.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:74:0)
[00:10:27.938] Timer tick, asking Buildbucket for the build status
[00:10:28.475] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:28.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:75:0) after 1m0s
[00:11:28.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:75:0)
[00:11:28.536] Timer tick, asking Buildbucket for the build status
[00:11:28.644] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:28.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:76:0) after 1m0s
[00:12:28.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:76:0)
[00:12:28.698] Timer tick, asking Buildbucket for the build status
[00:12:29.171] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:29.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:77:0) after 1m0s
[00:13:29.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:77:0)
[00:13:29.197] Timer tick, asking Buildbucket for the build status
[00:13:29.671] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:29.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:78:0) after 1m0s
[00:14:29.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:78:0)
[00:14:29.686] Timer tick, asking Buildbucket for the build status
[00:14:29.821] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:29.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:79:0) after 1m0s
[00:15:29.861] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:79:0)
[00:15:29.861] Timer tick, asking Buildbucket for the build status
[00:15:31.046] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:31.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:80:0) after 1m0s
[00:16:31.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:80:0)
[00:16:31.222] Timer tick, asking Buildbucket for the build status
[00:16:33.178] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:33.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:81:0) after 1m0s
[00:17:33.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:81:0)
[00:17:33.191] Timer tick, asking Buildbucket for the build status
[00:17:33.418] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:33.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:82:0) after 1m0s
[00:18:33.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:82:0)
[00:18:33.577] Timer tick, asking Buildbucket for the build status
[00:18:33.733] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:33.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:83:0) after 1m0s
[00:19:33.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:83:0)
[00:19:33.845] Timer tick, asking Buildbucket for the build status
[00:19:34.754] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:34.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:84:0) after 1m0s
[00:20:34.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:84:0)
[00:20:34.784] Timer tick, asking Buildbucket for the build status
[00:20:35.082] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:35.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:85:0) after 1m0s
[00:21:35.104] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:85:0)
[00:21:35.104] Timer tick, asking Buildbucket for the build status
[00:21:35.184] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:35.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:86:0) after 1m0s
[00:22:35.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:86:0)
[00:22:35.283] Timer tick, asking Buildbucket for the build status
[00:22:35.498] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:35.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:87:0) after 1m0s
[00:23:35.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:87:0)
[00:23:35.563] Timer tick, asking Buildbucket for the build status
[00:23:35.613] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:35.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:88:0) after 1m0s
[00:24:35.615] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:88:0)
[00:24:35.615] Timer tick, asking Buildbucket for the build status
[00:24:35.801] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:35.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:89:0) after 1m0s
[00:25:35.832] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:89:0)
[00:25:35.832] Timer tick, asking Buildbucket for the build status
[00:25:35.944] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:35.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:90:0) after 1m0s
[00:26:35.961] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:90:0)
[00:26:35.961] Timer tick, asking Buildbucket for the build status
[00:26:36.022] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:36.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:91:0) after 1m0s
[00:27:36.053] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:91:0)
[00:27:36.053] Timer tick, asking Buildbucket for the build status
[00:27:36.232] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:36.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:92:0) after 1m0s
[00:28:36.268] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:92:0)
[00:28:36.268] Timer tick, asking Buildbucket for the build status
[00:28:36.812] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:36.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:93:0) after 1m0s
[00:29:36.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:93:0)
[00:29:36.830] Timer tick, asking Buildbucket for the build status
[00:29:36.935] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:36.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:94:0) after 1m0s
[00:30:36.964] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:94:0)
[00:30:36.964] Timer tick, asking Buildbucket for the build status
[00:30:37.198] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:37.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:95:0) after 1m0s
[00:31:37.310] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:95:0)
[00:31:37.310] Timer tick, asking Buildbucket for the build status
[00:31:37.368] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:37.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:96:0) after 1m0s
[00:32:37.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:96:0)
[00:32:37.464] Timer tick, asking Buildbucket for the build status
[00:32:37.559] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:37.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:97:0) after 1m0s
[00:33:37.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:97:0)
[00:33:37.583] Timer tick, asking Buildbucket for the build status
[00:33:37.731] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:37.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:98:0) after 1m0s
[00:34:37.757] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:98:0)
[00:34:37.757] Timer tick, asking Buildbucket for the build status
[00:34:37.969] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:37.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:99:0) after 1m0s
[00:35:37.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:99:0)
[00:35:37.993] Timer tick, asking Buildbucket for the build status
[00:35:38.092] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:38.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:100:0) after 1m0s
[00:36:38.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:100:0)
[00:36:38.111] Timer tick, asking Buildbucket for the build status
[00:36:38.215] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:38.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:101:0) after 1m0s
[00:37:38.263] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:101:0)
[00:37:38.263] Timer tick, asking Buildbucket for the build status
[00:37:38.343] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:38.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:102:0) after 1m0s
[00:38:38.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:102:0)
[00:38:38.389] Timer tick, asking Buildbucket for the build status
[00:38:38.448] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:38.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:103:0) after 1m0s
[00:39:38.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:103:0)
[00:39:38.468] Timer tick, asking Buildbucket for the build status
[00:39:38.606] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:38.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:104:0) after 1m0s
[00:40:38.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:104:0)
[00:40:38.628] Timer tick, asking Buildbucket for the build status
[00:40:39.433] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:39.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:105:0) after 1m0s
[00:41:39.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:105:0)
[00:41:39.458] Timer tick, asking Buildbucket for the build status
[00:41:39.530] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:39.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:106:0) after 1m0s
[00:42:39.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:106:0)
[00:42:39.571] Timer tick, asking Buildbucket for the build status
[00:42:39.660] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:39.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:107:0) after 1m0s
[00:43:39.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:107:0)
[00:43:39.688] Timer tick, asking Buildbucket for the build status
[00:43:39.774] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:39.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:108:0) after 1m0s
[00:44:39.788] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:108:0)
[00:44:39.788] Timer tick, asking Buildbucket for the build status
[00:44:39.867] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:39.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:109:0) after 1m0s
[00:45:39.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:109:0)
[00:45:39.930] Timer tick, asking Buildbucket for the build status
[00:45:39.992] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:39.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:110:0) after 1m0s
[00:46:40.058] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:110:0)
[00:46:40.058] Timer tick, asking Buildbucket for the build status
[00:46:40.252] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:40.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:111:0) after 1m0s
[00:47:40.231] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:111:0)
[00:47:40.231] Timer tick, asking Buildbucket for the build status
[00:47:40.388] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:40.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:112:0) after 1m0s
[00:48:40.384] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:112:0)
[00:48:40.384] Timer tick, asking Buildbucket for the build status
[00:48:40.447] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:40.447] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:113:0) after 1m0s
[00:49:40.462] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:113:0)
[00:49:40.462] Timer tick, asking Buildbucket for the build status
[00:49:40.530] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:40.530] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:114:0) after 1m0s
[00:50:40.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:114:0)
[00:50:40.515] Timer tick, asking Buildbucket for the build status
[00:50:40.642] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:40.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:115:0) after 1m0s
[00:51:40.724] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:115:0)
[00:51:40.724] Timer tick, asking Buildbucket for the build status
[00:51:40.784] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:40.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:116:0) after 1m0s
[00:52:40.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:116:0)
[00:52:40.815] Timer tick, asking Buildbucket for the build status
[00:52:40.973] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:40.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:117:0) after 1m0s
[00:53:41.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:117:0)
[00:53:41.004] Timer tick, asking Buildbucket for the build status
[00:53:41.109] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:41.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:118:0) after 1m0s
[00:54:41.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:118:0)
[00:54:41.129] Timer tick, asking Buildbucket for the build status
[00:54:41.253] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:41.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:119:0) after 1m0s
[00:55:41.257] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:119:0)
[00:55:41.257] Timer tick, asking Buildbucket for the build status
[00:55:41.320] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:41.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:120:0) after 1m0s
[00:56:41.354] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:120:0)
[00:56:41.354] Timer tick, asking Buildbucket for the build status
[00:56:41.574] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:41.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:121:0) after 1m0s
[00:57:41.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:121:0)
[00:57:41.600] Timer tick, asking Buildbucket for the build status
[00:57:41.707] Build 8913896742644080144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:41.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079326804681852560:122:0) after 1m0s
[00:58:03.790] Received PubSub notification, asking Buildbucket for the build status
[00:58:03.839] Build 8913896742644080144: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:58:03.839] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557449883490958",
"created_by": "project:fuchsia",
"created_ts": "1557442639651281",
"failure_reason": "BUILD_FAILURE",
"id": "8913896742644080144",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"3478494c1fdcbbee3e22895dcd6e6919d6c05430\"}}",
"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-prod-1-us-central1-c-2-nw6d\", \"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\": \"3478494c1fdcbbee3e22895dcd6e6919d6c05430\", \"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-prod-1-us-central1-c-2-nw6d\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"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\": [\"4292-d8c05fa\"], \"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": "1557442659093048",
"status": "COMPLETED",
"status_changed_ts": "1557442659614605",
"tags": [
"builder:clang",
"buildset:commit/git/3478494c1fdcbbee3e22895dcd6e6919d6c05430",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3478494c1fdcbbee3e22895dcd6e6919d6c05430",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079326804681852560",
"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/8913896742644080144/+/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:44b7a24639e6b610",
"user_agent:luci-scheduler"
],
"updated_ts": "1557449883542691",
"url": "https://ci.chromium.org/b/8913896742644080144",
"utcnow_ts": "1557449883831861"
}
}
[00:58:03.839] Invocation finished in 2h0m45.514741101s with status FAILED