[14:50:22.253] New invocation is queued and will start shortly
[14:50:23.331] Starting the invocation (attempt 1)
[14:50:23.426] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:50:23.426] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:50:23.426] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9082528333792860656",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"b920a7f65b13237dc4d5b2b836b29a954fff440a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9082528333792860656",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/b920a7f65b13237dc4d5b2b836b29a954fff440a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b920a7f65b13237dc4d5b2b836b29a954fff440a",
"gitiles_ref:refs/heads/master"
]
}
[14:50:24.108] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1554389423504887",
"id": "8917098271818194032",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b920a7f65b13237dc4d5b2b836b29a954fff440a\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554389423884843",
"tags": [
"builder:clang",
"buildset:commit/git/b920a7f65b13237dc4d5b2b836b29a954fff440a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b920a7f65b13237dc4d5b2b836b29a954fff440a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082528333792860656",
"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/8917098271818194032/+/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": "1554389423884947",
"url": "https://ci.chromium.org/b/8917098271818194032",
"utcnow_ts": "1554389424099374"
}
}
[14:50:24.108] Task URL: https://ci.chromium.org/b/8917098271818194032
[14:50:24.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:2:0) after 1m0s
[14:50:44.680] Received PubSub notification, asking Buildbucket for the build status
[14:50:44.781] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:24.129] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:2:0)
[14:51:24.129] Timer tick, asking Buildbucket for the build status
[14:51:24.231] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:24.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:4:0) after 1m0s
[14:52:24.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:4:0)
[14:52:24.218] Timer tick, asking Buildbucket for the build status
[14:52:24.406] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:24.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:5:0) after 1m0s
[14:53:24.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:5:0)
[14:53:24.395] Timer tick, asking Buildbucket for the build status
[14:53:24.463] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:24.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:6:0) after 1m0s
[14:54:24.443] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:6:0)
[14:54:24.443] Timer tick, asking Buildbucket for the build status
[14:54:24.497] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:24.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:7:0) after 1m0s
[14:55:24.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:7:0)
[14:55:24.518] Timer tick, asking Buildbucket for the build status
[14:55:24.690] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:24.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:8:0) after 1m0s
[14:56:24.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:8:0)
[14:56:24.711] Timer tick, asking Buildbucket for the build status
[14:56:24.817] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:24.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:9:0) after 1m0s
[14:57:24.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:9:0)
[14:57:24.823] Timer tick, asking Buildbucket for the build status
[14:57:24.895] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:24.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:10:0) after 1m0s
[14:58:24.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:10:0)
[14:58:24.917] Timer tick, asking Buildbucket for the build status
[14:58:24.975] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:24.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:11:0) after 1m0s
[14:59:24.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:11:0)
[14:59:24.996] Timer tick, asking Buildbucket for the build status
[14:59:25.180] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:25.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:12:0) after 1m0s
[15:00:25.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:12:0)
[15:00:25.206] Timer tick, asking Buildbucket for the build status
[15:00:25.498] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:25.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:13:0) after 1m0s
[15:01:25.515] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:13:0)
[15:01:25.515] Timer tick, asking Buildbucket for the build status
[15:01:25.555] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:25.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:14:0) after 1m0s
[15:02:25.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:14:0)
[15:02:25.576] Timer tick, asking Buildbucket for the build status
[15:02:25.638] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:25.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:15:0) after 1m0s
[15:03:25.625] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:15:0)
[15:03:25.625] Timer tick, asking Buildbucket for the build status
[15:03:25.785] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:25.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:16:0) after 1m0s
[15:04:25.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:16:0)
[15:04:25.809] Timer tick, asking Buildbucket for the build status
[15:04:25.908] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:25.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:17:0) after 1m0s
[15:05:25.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:17:0)
[15:05:25.930] Timer tick, asking Buildbucket for the build status
[15:05:26.013] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:26.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:18:0) after 1m0s
[15:06:26.047] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:18:0)
[15:06:26.047] Timer tick, asking Buildbucket for the build status
[15:06:26.095] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:26.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:19:0) after 1m0s
[15:07:26.123] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:19:0)
[15:07:26.123] Timer tick, asking Buildbucket for the build status
[15:07:26.356] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:26.356] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:20:0) after 1m0s
[15:08:26.372] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:20:0)
[15:08:26.372] Timer tick, asking Buildbucket for the build status
[15:08:26.475] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:26.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:21:0) after 1m0s
[15:09:26.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:21:0)
[15:09:26.503] Timer tick, asking Buildbucket for the build status
[15:09:26.590] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:26.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:22:0) after 1m0s
[15:10:26.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:22:0)
[15:10:26.564] Timer tick, asking Buildbucket for the build status
[15:10:26.627] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:26.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:23:0) after 1m0s
[15:11:26.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:23:0)
[15:11:26.692] Timer tick, asking Buildbucket for the build status
[15:11:26.858] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:26.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:24:0) after 1m0s
[15:12:26.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:24:0)
[15:12:26.881] Timer tick, asking Buildbucket for the build status
[15:12:26.956] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:26.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:25:0) after 1m0s
[15:13:27.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:25:0)
[15:13:27.102] Timer tick, asking Buildbucket for the build status
[15:13:27.251] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:27.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:26:0) after 1m0s
[15:14:27.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:26:0)
[15:14:27.289] Timer tick, asking Buildbucket for the build status
[15:14:27.327] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:27.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:27:0) after 1m0s
[15:15:27.354] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:27:0)
[15:15:27.354] Timer tick, asking Buildbucket for the build status
[15:15:27.497] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:27.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:28:0) after 1m0s
[15:16:27.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:28:0)
[15:16:27.610] Timer tick, asking Buildbucket for the build status
[15:16:27.663] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:27.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:29:0) after 1m0s
[15:17:27.694] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:29:0)
[15:17:27.694] Timer tick, asking Buildbucket for the build status
[15:17:27.910] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:27.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:30:0) after 1m0s
[15:18:27.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:30:0)
[15:18:27.906] Timer tick, asking Buildbucket for the build status
[15:18:27.946] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:27.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:31:0) after 1m0s
[15:19:27.965] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:31:0)
[15:19:27.965] Timer tick, asking Buildbucket for the build status
[15:19:28.033] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:28.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:32:0) after 1m0s
[15:20:28.054] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:32:0)
[15:20:28.054] Timer tick, asking Buildbucket for the build status
[15:20:28.112] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:28.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:33:0) after 1m0s
[15:21:28.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:33:0)
[15:21:28.152] Timer tick, asking Buildbucket for the build status
[15:21:28.222] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:28.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:34:0) after 1m0s
[15:22:28.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:34:0)
[15:22:28.218] Timer tick, asking Buildbucket for the build status
[15:22:28.294] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:28.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:35:0) after 1m0s
[15:23:28.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:35:0)
[15:23:28.303] Timer tick, asking Buildbucket for the build status
[15:23:28.436] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:28.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:36:0) after 1m0s
[15:24:28.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:36:0)
[15:24:28.506] Timer tick, asking Buildbucket for the build status
[15:24:28.550] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:28.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:37:0) after 1m0s
[15:25:28.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:37:0)
[15:25:28.656] Timer tick, asking Buildbucket for the build status
[15:25:28.746] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:28.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:38:0) after 1m0s
[15:26:28.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:38:0)
[15:26:28.776] Timer tick, asking Buildbucket for the build status
[15:26:28.822] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:28.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:39:0) after 1m0s
[15:27:28.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:39:0)
[15:27:28.943] Timer tick, asking Buildbucket for the build status
[15:27:29.098] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:29.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:40:0) after 1m0s
[15:28:29.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:40:0)
[15:28:29.127] Timer tick, asking Buildbucket for the build status
[15:28:29.168] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:29.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:41:0) after 1m0s
[15:29:29.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:41:0)
[15:29:29.209] Timer tick, asking Buildbucket for the build status
[15:29:29.282] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:29.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:42:0) after 1m0s
[15:30:29.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:42:0)
[15:30:29.318] Timer tick, asking Buildbucket for the build status
[15:30:29.354] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:29.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:43:0) after 1m0s
[15:31:29.401] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:43:0)
[15:31:29.401] Timer tick, asking Buildbucket for the build status
[15:31:29.659] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:29.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:44:0) after 1m0s
[15:32:29.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:44:0)
[15:32:29.683] Timer tick, asking Buildbucket for the build status
[15:32:29.727] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:29.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:45:0) after 1m0s
[15:33:29.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:45:0)
[15:33:29.770] Timer tick, asking Buildbucket for the build status
[15:33:29.841] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:29.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:46:0) after 1m0s
[15:34:29.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:46:0)
[15:34:29.934] Timer tick, asking Buildbucket for the build status
[15:34:30.077] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:30.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:47:0) after 1m0s
[15:35:30.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:47:0)
[15:35:30.113] Timer tick, asking Buildbucket for the build status
[15:35:30.204] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:30.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:48:0) after 1m0s
[15:36:30.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:48:0)
[15:36:30.213] Timer tick, asking Buildbucket for the build status
[15:36:30.265] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:30.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:49:0) after 1m0s
[15:37:30.303] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:49:0)
[15:37:30.303] Timer tick, asking Buildbucket for the build status
[15:37:30.365] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:30.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:50:0) after 1m0s
[15:38:30.368] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:50:0)
[15:38:30.368] Timer tick, asking Buildbucket for the build status
[15:38:30.495] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:30.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:51:0) after 1m0s
[15:39:30.521] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:51:0)
[15:39:30.521] Timer tick, asking Buildbucket for the build status
[15:39:30.614] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:30.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:52:0) after 1m0s
[15:40:30.630] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:52:0)
[15:40:30.630] Timer tick, asking Buildbucket for the build status
[15:40:30.669] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:30.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:53:0) after 1m0s
[15:41:30.652] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:53:0)
[15:41:30.652] Timer tick, asking Buildbucket for the build status
[15:41:30.730] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:30.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:54:0) after 1m0s
[15:42:30.754] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:54:0)
[15:42:30.754] Timer tick, asking Buildbucket for the build status
[15:42:30.799] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:30.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:55:0) after 1m0s
[15:43:30.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:55:0)
[15:43:30.822] Timer tick, asking Buildbucket for the build status
[15:43:30.984] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:30.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:56:0) after 1m0s
[15:44:31.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:56:0)
[15:44:31.005] Timer tick, asking Buildbucket for the build status
[15:44:31.047] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:31.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:57:0) after 1m0s
[15:45:31.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:57:0)
[15:45:31.036] Timer tick, asking Buildbucket for the build status
[15:45:31.123] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:31.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:58:0) after 1m0s
[15:46:31.145] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:58:0)
[15:46:31.145] Timer tick, asking Buildbucket for the build status
[15:46:31.187] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:31.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:59:0) after 1m0s
[15:47:31.206] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:59:0)
[15:47:31.206] Timer tick, asking Buildbucket for the build status
[15:47:31.266] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:31.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:60:0) after 1m0s
[15:48:31.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:60:0)
[15:48:31.287] Timer tick, asking Buildbucket for the build status
[15:48:31.329] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:31.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:61:0) after 1m0s
[15:49:31.365] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:61:0)
[15:49:31.365] Timer tick, asking Buildbucket for the build status
[15:49:31.491] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:31.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:62:0) after 1m0s
[15:50:31.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:62:0)
[15:50:31.513] Timer tick, asking Buildbucket for the build status
[15:50:31.674] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:31.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:63:0) after 1m0s
[15:51:31.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:63:0)
[15:51:31.693] Timer tick, asking Buildbucket for the build status
[15:51:31.757] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:31.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:64:0) after 1m0s
[15:52:31.814] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:64:0)
[15:52:31.814] Timer tick, asking Buildbucket for the build status
[15:52:31.902] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:31.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:65:0) after 1m0s
[15:53:31.949] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:65:0)
[15:53:31.949] Timer tick, asking Buildbucket for the build status
[15:53:32.009] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:32.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:66:0) after 1m0s
[15:54:32.026] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:66:0)
[15:54:32.026] Timer tick, asking Buildbucket for the build status
[15:54:32.073] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:32.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:67:0) after 1m0s
[15:55:32.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:67:0)
[15:55:32.103] Timer tick, asking Buildbucket for the build status
[15:55:32.149] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:32.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:68:0) after 1m0s
[15:56:32.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:68:0)
[15:56:32.166] Timer tick, asking Buildbucket for the build status
[15:56:32.361] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:32.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:69:0) after 1m0s
[15:57:32.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:69:0)
[15:57:32.380] Timer tick, asking Buildbucket for the build status
[15:57:32.448] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:32.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:70:0) after 1m0s
[15:58:32.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:70:0)
[15:58:32.449] Timer tick, asking Buildbucket for the build status
[15:58:32.500] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:32.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:71:0) after 1m0s
[15:59:32.498] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:71:0)
[15:59:32.498] Timer tick, asking Buildbucket for the build status
[15:59:32.607] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:32.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:72:0) after 1m0s
[16:00:32.629] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:72:0)
[16:00:32.629] Timer tick, asking Buildbucket for the build status
[16:00:32.723] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:32.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:73:0) after 1m0s
[16:01:32.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:73:0)
[16:01:32.749] Timer tick, asking Buildbucket for the build status
[16:01:32.813] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:32.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:74:0) after 1m0s
[16:02:32.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:74:0)
[16:02:32.830] Timer tick, asking Buildbucket for the build status
[16:02:32.989] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:32.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:75:0) after 1m0s
[16:03:32.984] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:75:0)
[16:03:32.984] Timer tick, asking Buildbucket for the build status
[16:03:33.202] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:33.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:76:0) after 1m0s
[16:04:33.221] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:76:0)
[16:04:33.221] Timer tick, asking Buildbucket for the build status
[16:04:33.376] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:33.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:77:0) after 1m0s
[16:05:33.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:77:0)
[16:05:33.405] Timer tick, asking Buildbucket for the build status
[16:05:33.442] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:33.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:78:0) after 1m0s
[16:06:33.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:78:0)
[16:06:33.468] Timer tick, asking Buildbucket for the build status
[16:06:33.604] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:33.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:79:0) after 1m0s
[16:07:33.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:79:0)
[16:07:33.609] Timer tick, asking Buildbucket for the build status
[16:07:33.785] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:33.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:80:0) after 1m0s
[16:08:33.809] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:80:0)
[16:08:33.809] Timer tick, asking Buildbucket for the build status
[16:08:33.936] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:33.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:81:0) after 1m0s
[16:09:33.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:81:0)
[16:09:33.956] Timer tick, asking Buildbucket for the build status
[16:09:34.186] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:34.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:82:0) after 1m0s
[16:10:34.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:82:0)
[16:10:34.208] Timer tick, asking Buildbucket for the build status
[16:10:34.250] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:34.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:83:0) after 1m0s
[16:11:34.270] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:83:0)
[16:11:34.270] Timer tick, asking Buildbucket for the build status
[16:11:34.498] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:34.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:84:0) after 1m0s
[16:12:34.526] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:84:0)
[16:12:34.526] Timer tick, asking Buildbucket for the build status
[16:12:34.564] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:34.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:85:0) after 1m0s
[16:13:34.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:85:0)
[16:13:34.560] Timer tick, asking Buildbucket for the build status
[16:13:34.608] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:34.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:86:0) after 1m0s
[16:14:34.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:86:0)
[16:14:34.614] Timer tick, asking Buildbucket for the build status
[16:14:34.994] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:34.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:87:0) after 1m0s
[16:15:35.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:87:0)
[16:15:35.019] Timer tick, asking Buildbucket for the build status
[16:15:35.109] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:35.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:88:0) after 1m0s
[16:16:35.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:88:0)
[16:16:35.149] Timer tick, asking Buildbucket for the build status
[16:16:35.221] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:35.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:89:0) after 1m0s
[16:17:35.240] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:89:0)
[16:17:35.240] Timer tick, asking Buildbucket for the build status
[16:17:35.448] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:35.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:90:0) after 1m0s
[16:18:35.459] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:90:0)
[16:18:35.459] Timer tick, asking Buildbucket for the build status
[16:18:35.546] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:35.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:91:0) after 1m0s
[16:19:35.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:91:0)
[16:19:35.567] Timer tick, asking Buildbucket for the build status
[16:19:35.647] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:35.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:92:0) after 1m0s
[16:20:35.670] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:92:0)
[16:20:35.670] Timer tick, asking Buildbucket for the build status
[16:20:35.711] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:35.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:93:0) after 1m0s
[16:21:35.688] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:93:0)
[16:21:35.688] Timer tick, asking Buildbucket for the build status
[16:21:35.757] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:35.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:94:0) after 1m0s
[16:22:35.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:94:0)
[16:22:35.753] Timer tick, asking Buildbucket for the build status
[16:22:35.795] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:35.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:95:0) after 1m0s
[16:23:35.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:95:0)
[16:23:35.807] Timer tick, asking Buildbucket for the build status
[16:23:35.875] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:35.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:96:0) after 1m0s
[16:24:35.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:96:0)
[16:24:35.898] Timer tick, asking Buildbucket for the build status
[16:24:36.025] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:36.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:97:0) after 1m0s
[16:25:36.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:97:0)
[16:25:36.008] Timer tick, asking Buildbucket for the build status
[16:25:36.072] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:36.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:98:0) after 1m0s
[16:26:36.099] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:98:0)
[16:26:36.099] Timer tick, asking Buildbucket for the build status
[16:26:36.197] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:36.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:99:0) after 1m0s
[16:27:36.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:99:0)
[16:27:36.209] Timer tick, asking Buildbucket for the build status
[16:27:36.296] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:36.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:100:0) after 1m0s
[16:28:36.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:100:0)
[16:28:36.328] Timer tick, asking Buildbucket for the build status
[16:28:36.383] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:36.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:101:0) after 1m0s
[16:29:36.404] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:101:0)
[16:29:36.404] Timer tick, asking Buildbucket for the build status
[16:29:36.457] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:36.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:102:0) after 1m0s
[16:30:36.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:102:0)
[16:30:36.480] Timer tick, asking Buildbucket for the build status
[16:30:36.522] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:36.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:103:0) after 1m0s
[16:31:36.557] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:103:0)
[16:31:36.557] Timer tick, asking Buildbucket for the build status
[16:31:36.672] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:36.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:104:0) after 1m0s
[16:32:36.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:104:0)
[16:32:36.715] Timer tick, asking Buildbucket for the build status
[16:32:36.756] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:36.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:105:0) after 1m0s
[16:33:36.776] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:105:0)
[16:33:36.776] Timer tick, asking Buildbucket for the build status
[16:33:36.980] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:36.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:106:0) after 1m0s
[16:34:37.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:106:0)
[16:34:37.004] Timer tick, asking Buildbucket for the build status
[16:34:37.045] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:37.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:107:0) after 1m0s
[16:35:37.036] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:107:0)
[16:35:37.036] Timer tick, asking Buildbucket for the build status
[16:35:37.096] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:37.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:108:0) after 1m0s
[16:36:37.120] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:108:0)
[16:36:37.120] Timer tick, asking Buildbucket for the build status
[16:36:37.167] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:37.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:109:0) after 1m0s
[16:37:37.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:109:0)
[16:37:37.189] Timer tick, asking Buildbucket for the build status
[16:37:37.379] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:37.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:110:0) after 1m0s
[16:38:37.402] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:110:0)
[16:38:37.402] Timer tick, asking Buildbucket for the build status
[16:38:37.479] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:37.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:111:0) after 1m0s
[16:39:37.541] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:111:0)
[16:39:37.541] Timer tick, asking Buildbucket for the build status
[16:39:37.592] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:37.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:112:0) after 1m0s
[16:40:37.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:112:0)
[16:40:37.594] Timer tick, asking Buildbucket for the build status
[16:40:37.640] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:37.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:113:0) after 1m0s
[16:41:37.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:113:0)
[16:41:37.666] Timer tick, asking Buildbucket for the build status
[16:41:37.883] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:37.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:114:0) after 1m0s
[16:42:37.881] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:114:0)
[16:42:37.881] Timer tick, asking Buildbucket for the build status
[16:42:37.967] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:37.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:115:0) after 1m0s
[16:43:37.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:115:0)
[16:43:37.992] Timer tick, asking Buildbucket for the build status
[16:43:38.100] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:38.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:116:0) after 1m0s
[16:44:38.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:116:0)
[16:44:38.103] Timer tick, asking Buildbucket for the build status
[16:44:38.154] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:38.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:117:0) after 1m0s
[16:45:38.144] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:117:0)
[16:45:38.144] Timer tick, asking Buildbucket for the build status
[16:45:38.241] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:38.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:118:0) after 1m0s
[16:46:38.233] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:118:0)
[16:46:38.233] Timer tick, asking Buildbucket for the build status
[16:46:38.276] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:38.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:119:0) after 1m0s
[16:47:38.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:119:0)
[16:47:38.298] Timer tick, asking Buildbucket for the build status
[16:47:38.400] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:38.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:120:0) after 1m0s
[16:48:38.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:120:0)
[16:48:38.430] Timer tick, asking Buildbucket for the build status
[16:48:38.469] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:38.469] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:121:0) after 1m0s
[16:49:38.491] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:121:0)
[16:49:38.491] Timer tick, asking Buildbucket for the build status
[16:49:38.806] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:38.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:122:0) after 1m0s
[16:50:38.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:122:0)
[16:50:38.795] Timer tick, asking Buildbucket for the build status
[16:50:38.858] Build 8917098271818194032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:38.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9082528333792860656:123:0) after 1m0s
[16:51:07.374] Received PubSub notification, asking Buildbucket for the build status
[16:51:07.575] Build 8917098271818194032: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:51:07.575] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1554396666736139",
"created_by": "project:fuchsia",
"created_ts": "1554389423504887",
"failure_reason": "BUILD_FAILURE",
"id": "8917098271818194032",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"b920a7f65b13237dc4d5b2b836b29a954fff440a\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-debian-1-ffce87c5-us-central1-b-fh56\", \"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\": \"b920a7f65b13237dc4d5b2b836b29a954fff440a\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-debian-1-ffce87c5-us-central1-b-fh56\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190319-3f58170\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4240-c9c218d\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1554389443406041",
"status": "COMPLETED",
"status_changed_ts": "1554389444217721",
"tags": [
"builder:clang",
"buildset:commit/git/b920a7f65b13237dc4d5b2b836b29a954fff440a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/b920a7f65b13237dc4d5b2b836b29a954fff440a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082528333792860656",
"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/8917098271818194032/+/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:4401a5e186be1f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1554396666782839",
"url": "https://ci.chromium.org/b/8917098271818194032",
"utcnow_ts": "1554396667515075"
}
}
[16:51:07.575] Invocation finished in 2h0m45.338228606s with status FAILED