[20:31:00.353] New invocation is queued and will start shortly
[20:31:01.521] Starting the invocation (attempt 1)
[20:31:01.558] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:31:01.558] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:31:01.558] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9080332575697224768",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"85bacd0f957887049f1cd782477e4064b29f4558\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9080332575697224768",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/85bacd0f957887049f1cd782477e4064b29f4558",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/85bacd0f957887049f1cd782477e4064b29f4558",
"gitiles_ref:refs/heads/master"
]
}
[20:31:02.635] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556483461825863",
"id": "8914902513491341552",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"85bacd0f957887049f1cd782477e4064b29f4558\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556483462424661",
"tags": [
"builder:clang",
"buildset:commit/git/85bacd0f957887049f1cd782477e4064b29f4558",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/85bacd0f957887049f1cd782477e4064b29f4558",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080332575697224768",
"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/8914902513491341552/+/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": "1556483462426585",
"url": "https://ci.chromium.org/b/8914902513491341552",
"utcnow_ts": "1556483462626885"
}
}
[20:31:02.635] Task URL: https://ci.chromium.org/b/8914902513491341552
[20:31:02.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:2:0) after 1m0s
[20:31:22.519] Received PubSub notification, asking Buildbucket for the build status
[20:31:22.592] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:02.657] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:2:0)
[20:32:02.657] Timer tick, asking Buildbucket for the build status
[20:32:02.979] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:02.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:4:0) after 1m0s
[20:33:03.006] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:4:0)
[20:33:03.006] Timer tick, asking Buildbucket for the build status
[20:33:03.473] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:03.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:5:0) after 1m0s
[20:34:03.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:5:0)
[20:34:03.493] Timer tick, asking Buildbucket for the build status
[20:34:03.528] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:03.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:6:0) after 1m0s
[20:35:03.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:6:0)
[20:35:03.553] Timer tick, asking Buildbucket for the build status
[20:35:03.630] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:03.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:7:0) after 1m0s
[20:36:03.640] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:7:0)
[20:36:03.640] Timer tick, asking Buildbucket for the build status
[20:36:03.708] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:03.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:8:0) after 1m0s
[20:37:03.726] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:8:0)
[20:37:03.726] Timer tick, asking Buildbucket for the build status
[20:37:03.851] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:03.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:9:0) after 1m0s
[20:38:03.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:9:0)
[20:38:03.870] Timer tick, asking Buildbucket for the build status
[20:38:07.382] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:07.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:10:0) after 1m0s
[20:39:07.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:10:0)
[20:39:07.388] Timer tick, asking Buildbucket for the build status
[20:39:07.444] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:07.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:11:0) after 1m0s
[20:40:07.468] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:11:0)
[20:40:07.468] Timer tick, asking Buildbucket for the build status
[20:40:07.526] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:07.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:12:0) after 1m0s
[20:41:07.546] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:12:0)
[20:41:07.546] Timer tick, asking Buildbucket for the build status
[20:41:07.650] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:07.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:13:0) after 1m0s
[20:42:07.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:13:0)
[20:42:07.674] Timer tick, asking Buildbucket for the build status
[20:42:07.728] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:07.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:14:0) after 1m0s
[20:43:07.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:14:0)
[20:43:07.798] Timer tick, asking Buildbucket for the build status
[20:43:07.868] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:07.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:15:0) after 1m0s
[20:44:08.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:15:0)
[20:44:08.063] Timer tick, asking Buildbucket for the build status
[20:44:08.280] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:08.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:16:0) after 1m0s
[20:45:08.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:16:0)
[20:45:08.299] Timer tick, asking Buildbucket for the build status
[20:45:08.413] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:08.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:17:0) after 1m0s
[20:46:08.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:17:0)
[20:46:08.458] Timer tick, asking Buildbucket for the build status
[20:46:08.519] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:08.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:18:0) after 1m0s
[20:47:08.544] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:18:0)
[20:47:08.544] Timer tick, asking Buildbucket for the build status
[20:47:08.806] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:08.807] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:19:0) after 1m0s
[20:48:08.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:19:0)
[20:48:08.888] Timer tick, asking Buildbucket for the build status
[20:48:08.955] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:08.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:20:0) after 1m0s
[20:49:08.974] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:20:0)
[20:49:08.974] Timer tick, asking Buildbucket for the build status
[20:49:09.587] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:09.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:21:0) after 1m0s
[20:50:09.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:21:0)
[20:50:09.614] Timer tick, asking Buildbucket for the build status
[20:50:09.854] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:09.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:22:0) after 1m0s
[20:51:09.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:22:0)
[20:51:09.879] Timer tick, asking Buildbucket for the build status
[20:51:09.938] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:09.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:23:0) after 1m0s
[20:52:09.959] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:23:0)
[20:52:09.959] Timer tick, asking Buildbucket for the build status
[20:52:10.183] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:10.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:24:0) after 1m0s
[20:53:10.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:24:0)
[20:53:10.207] Timer tick, asking Buildbucket for the build status
[20:53:10.396] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:10.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:25:0) after 1m0s
[20:54:10.416] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:25:0)
[20:54:10.416] Timer tick, asking Buildbucket for the build status
[20:54:10.578] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:10.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:26:0) after 1m0s
[20:55:10.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:26:0)
[20:55:10.598] Timer tick, asking Buildbucket for the build status
[20:55:10.653] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:10.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:27:0) after 1m0s
[20:56:10.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:27:0)
[20:56:10.642] Timer tick, asking Buildbucket for the build status
[20:56:11.090] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:11.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:28:0) after 1m0s
[20:57:11.101] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:28:0)
[20:57:11.101] Timer tick, asking Buildbucket for the build status
[20:57:11.178] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:11.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:29:0) after 1m0s
[20:58:11.183] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:29:0)
[20:58:11.183] Timer tick, asking Buildbucket for the build status
[20:58:11.281] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:11.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:30:0) after 1m0s
[20:59:11.298] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:30:0)
[20:59:11.298] Timer tick, asking Buildbucket for the build status
[20:59:11.365] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:11.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:31:0) after 1m0s
[21:00:11.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:31:0)
[21:00:11.392] Timer tick, asking Buildbucket for the build status
[21:00:11.659] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:11.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:32:0) after 1m0s
[21:01:11.667] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:32:0)
[21:01:11.667] Timer tick, asking Buildbucket for the build status
[21:01:11.799] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:11.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:33:0) after 1m0s
[21:02:11.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:33:0)
[21:02:11.801] Timer tick, asking Buildbucket for the build status
[21:02:11.909] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:11.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:34:0) after 1m0s
[21:03:11.928] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:34:0)
[21:03:11.928] Timer tick, asking Buildbucket for the build status
[21:03:12.015] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:12.015] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:35:0) after 1m0s
[21:04:11.995] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:35:0)
[21:04:11.995] Timer tick, asking Buildbucket for the build status
[21:04:12.174] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:12.174] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:36:0) after 1m0s
[21:05:12.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:36:0)
[21:05:12.157] Timer tick, asking Buildbucket for the build status
[21:05:12.274] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:12.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:37:0) after 1m0s
[21:06:12.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:37:0)
[21:06:12.295] Timer tick, asking Buildbucket for the build status
[21:06:12.434] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:12.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:38:0) after 1m0s
[21:07:12.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:38:0)
[21:07:12.451] Timer tick, asking Buildbucket for the build status
[21:07:12.958] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:12.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:39:0) after 1m0s
[21:08:12.977] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:39:0)
[21:08:12.977] Timer tick, asking Buildbucket for the build status
[21:08:13.039] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:13.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:40:0) after 1m0s
[21:09:13.050] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:40:0)
[21:09:13.050] Timer tick, asking Buildbucket for the build status
[21:09:13.209] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:13.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:41:0) after 1m0s
[21:10:13.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:41:0)
[21:10:13.223] Timer tick, asking Buildbucket for the build status
[21:10:13.399] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:13.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:42:0) after 1m0s
[21:11:13.430] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:42:0)
[21:11:13.430] Timer tick, asking Buildbucket for the build status
[21:11:13.584] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:13.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:43:0) after 1m0s
[21:12:13.607] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:43:0)
[21:12:13.607] Timer tick, asking Buildbucket for the build status
[21:12:13.665] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:13.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:44:0) after 1m0s
[21:13:13.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:44:0)
[21:13:13.696] Timer tick, asking Buildbucket for the build status
[21:13:13.773] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:13.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:45:0) after 1m0s
[21:14:13.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:45:0)
[21:14:13.795] Timer tick, asking Buildbucket for the build status
[21:14:13.855] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:13.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:46:0) after 1m0s
[21:15:13.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:46:0)
[21:15:13.889] Timer tick, asking Buildbucket for the build status
[21:15:13.968] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:13.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:47:0) after 1m0s
[21:16:13.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:47:0)
[21:16:13.989] Timer tick, asking Buildbucket for the build status
[21:16:14.059] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:14.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:48:0) after 1m0s
[21:17:14.079] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:48:0)
[21:17:14.079] Timer tick, asking Buildbucket for the build status
[21:17:14.138] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:14.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:49:0) after 1m0s
[21:18:14.159] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:49:0)
[21:18:14.159] Timer tick, asking Buildbucket for the build status
[21:18:14.208] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:14.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:50:0) after 1m0s
[21:19:14.230] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:50:0)
[21:19:14.230] Timer tick, asking Buildbucket for the build status
[21:19:14.323] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:14.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:51:0) after 1m0s
[21:20:14.360] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:51:0)
[21:20:14.360] Timer tick, asking Buildbucket for the build status
[21:20:14.415] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:14.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:52:0) after 1m0s
[21:21:14.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:52:0)
[21:21:14.399] Timer tick, asking Buildbucket for the build status
[21:21:14.578] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:14.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:53:0) after 1m0s
[21:22:14.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:53:0)
[21:22:14.606] Timer tick, asking Buildbucket for the build status
[21:22:14.671] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:14.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:54:0) after 1m0s
[21:23:14.695] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:54:0)
[21:23:14.695] Timer tick, asking Buildbucket for the build status
[21:23:14.798] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:14.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:55:0) after 1m0s
[21:24:14.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:55:0)
[21:24:14.822] Timer tick, asking Buildbucket for the build status
[21:24:14.890] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:14.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:56:0) after 1m0s
[21:25:14.913] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:56:0)
[21:25:14.913] Timer tick, asking Buildbucket for the build status
[21:25:15.181] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:15.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:57:0) after 1m0s
[21:26:15.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:57:0)
[21:26:15.168] Timer tick, asking Buildbucket for the build status
[21:26:15.229] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:15.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:58:0) after 1m0s
[21:27:15.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:58:0)
[21:27:15.247] Timer tick, asking Buildbucket for the build status
[21:27:15.430] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:15.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:59:0) after 1m0s
[21:28:15.459] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:59:0)
[21:28:15.459] Timer tick, asking Buildbucket for the build status
[21:28:15.588] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:15.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:60:0) after 1m0s
[21:29:15.624] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:60:0)
[21:29:15.624] Timer tick, asking Buildbucket for the build status
[21:29:15.680] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:15.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:61:0) after 1m0s
[21:30:15.711] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:61:0)
[21:30:15.711] Timer tick, asking Buildbucket for the build status
[21:30:15.774] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:15.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:62:0) after 1m0s
[21:31:15.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:62:0)
[21:31:15.804] Timer tick, asking Buildbucket for the build status
[21:31:15.918] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:15.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:63:0) after 1m0s
[21:32:15.895] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:63:0)
[21:32:15.895] Timer tick, asking Buildbucket for the build status
[21:32:16.093] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:16.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:64:0) after 1m0s
[21:33:16.143] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:64:0)
[21:33:16.143] Timer tick, asking Buildbucket for the build status
[21:33:16.460] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:16.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:65:0) after 1m0s
[21:34:16.474] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:65:0)
[21:34:16.474] Timer tick, asking Buildbucket for the build status
[21:34:16.711] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:16.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:66:0) after 1m0s
[21:35:16.731] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:66:0)
[21:35:16.731] Timer tick, asking Buildbucket for the build status
[21:35:16.855] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:16.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:67:0) after 1m0s
[21:36:16.876] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:67:0)
[21:36:16.876] Timer tick, asking Buildbucket for the build status
[21:36:16.971] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:16.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:68:0) after 1m0s
[21:37:16.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:68:0)
[21:37:16.993] Timer tick, asking Buildbucket for the build status
[21:37:17.256] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:17.256] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:69:0) after 1m0s
[21:38:17.275] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:69:0)
[21:38:17.275] Timer tick, asking Buildbucket for the build status
[21:38:17.429] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:17.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:70:0) after 1m0s
[21:39:17.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:70:0)
[21:39:17.446] Timer tick, asking Buildbucket for the build status
[21:39:17.565] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:17.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:71:0) after 1m0s
[21:40:17.586] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:71:0)
[21:40:17.586] Timer tick, asking Buildbucket for the build status
[21:40:17.696] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:17.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:72:0) after 1m0s
[21:41:17.717] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:72:0)
[21:41:17.717] Timer tick, asking Buildbucket for the build status
[21:41:17.776] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:17.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:73:0) after 1m0s
[21:42:17.795] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:73:0)
[21:42:17.795] Timer tick, asking Buildbucket for the build status
[21:42:17.859] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:17.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:74:0) after 1m0s
[21:43:17.878] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:74:0)
[21:43:17.878] Timer tick, asking Buildbucket for the build status
[21:43:17.938] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:17.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:75:0) after 1m0s
[21:44:17.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:75:0)
[21:44:17.966] Timer tick, asking Buildbucket for the build status
[21:44:18.026] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:18.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:76:0) after 1m0s
[21:45:18.027] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:76:0)
[21:45:18.027] Timer tick, asking Buildbucket for the build status
[21:45:18.089] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:18.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:77:0) after 1m0s
[21:46:18.132] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:77:0)
[21:46:18.132] Timer tick, asking Buildbucket for the build status
[21:46:18.177] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:18.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:78:0) after 1m0s
[21:47:18.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:78:0)
[21:47:18.203] Timer tick, asking Buildbucket for the build status
[21:47:18.453] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:18.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:79:0) after 1m0s
[21:48:18.473] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:79:0)
[21:48:18.473] Timer tick, asking Buildbucket for the build status
[21:48:18.529] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:18.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:80:0) after 1m0s
[21:49:18.551] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:80:0)
[21:49:18.551] Timer tick, asking Buildbucket for the build status
[21:49:18.598] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:18.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:81:0) after 1m0s
[21:50:18.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:81:0)
[21:50:18.578] Timer tick, asking Buildbucket for the build status
[21:50:18.650] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:18.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:82:0) after 1m0s
[21:51:18.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:82:0)
[21:51:18.671] Timer tick, asking Buildbucket for the build status
[21:51:18.758] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:18.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:83:0) after 1m0s
[21:52:18.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:83:0)
[21:52:18.779] Timer tick, asking Buildbucket for the build status
[21:52:18.933] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:18.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:84:0) after 1m0s
[21:53:18.934] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:84:0)
[21:53:18.934] Timer tick, asking Buildbucket for the build status
[21:53:18.999] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:18.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:85:0) after 1m0s
[21:54:19.030] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:85:0)
[21:54:19.030] Timer tick, asking Buildbucket for the build status
[21:54:19.089] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:19.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:86:0) after 1m0s
[21:55:19.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:86:0)
[21:55:19.102] Timer tick, asking Buildbucket for the build status
[21:55:19.154] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:19.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:87:0) after 1m0s
[21:56:19.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:87:0)
[21:56:19.177] Timer tick, asking Buildbucket for the build status
[21:56:19.375] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:19.375] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:88:0) after 1m0s
[21:57:19.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:88:0)
[21:57:19.397] Timer tick, asking Buildbucket for the build status
[21:57:19.678] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:19.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:89:0) after 1m0s
[21:58:19.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:89:0)
[21:58:19.806] Timer tick, asking Buildbucket for the build status
[21:58:19.863] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:19.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:90:0) after 1m0s
[21:59:19.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:90:0)
[21:59:19.884] Timer tick, asking Buildbucket for the build status
[21:59:19.926] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:19.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:91:0) after 1m0s
[22:00:19.948] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:91:0)
[22:00:19.948] Timer tick, asking Buildbucket for the build status
[22:00:20.101] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:20.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:92:0) after 1m0s
[22:01:20.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:92:0)
[22:01:20.131] Timer tick, asking Buildbucket for the build status
[22:01:20.338] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:20.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:93:0) after 1m0s
[22:02:20.366] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:93:0)
[22:02:20.366] Timer tick, asking Buildbucket for the build status
[22:02:20.409] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:20.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:94:0) after 1m0s
[22:03:20.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:94:0)
[22:03:20.438] Timer tick, asking Buildbucket for the build status
[22:03:20.524] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:20.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:95:0) after 1m0s
[22:04:20.555] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:95:0)
[22:04:20.555] Timer tick, asking Buildbucket for the build status
[22:04:20.692] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:20.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:96:0) after 1m0s
[22:05:20.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:96:0)
[22:05:20.734] Timer tick, asking Buildbucket for the build status
[22:05:20.796] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:20.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:97:0) after 1m0s
[22:06:20.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:97:0)
[22:06:20.820] Timer tick, asking Buildbucket for the build status
[22:06:20.895] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:20.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:98:0) after 1m0s
[22:07:20.921] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:98:0)
[22:07:20.921] Timer tick, asking Buildbucket for the build status
[22:07:20.995] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:20.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:99:0) after 1m0s
[22:08:21.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:99:0)
[22:08:21.015] Timer tick, asking Buildbucket for the build status
[22:08:21.106] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:21.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:100:0) after 1m0s
[22:09:21.142] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:100:0)
[22:09:21.142] Timer tick, asking Buildbucket for the build status
[22:09:21.191] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:21.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:101:0) after 1m0s
[22:10:21.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:101:0)
[22:10:21.203] Timer tick, asking Buildbucket for the build status
[22:10:21.471] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:21.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:102:0) after 1m0s
[22:11:21.494] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:102:0)
[22:11:21.494] Timer tick, asking Buildbucket for the build status
[22:11:21.598] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:21.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:103:0) after 1m0s
[22:12:21.671] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:103:0)
[22:12:21.671] Timer tick, asking Buildbucket for the build status
[22:12:21.901] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:21.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:104:0) after 1m0s
[22:13:21.924] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:104:0)
[22:13:21.924] Timer tick, asking Buildbucket for the build status
[22:13:22.056] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:22.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:105:0) after 1m0s
[22:14:22.078] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:105:0)
[22:14:22.078] Timer tick, asking Buildbucket for the build status
[22:14:22.149] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:22.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:106:0) after 1m0s
[22:15:22.170] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:106:0)
[22:15:22.170] Timer tick, asking Buildbucket for the build status
[22:15:22.209] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:22.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:107:0) after 1m0s
[22:16:22.204] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:107:0)
[22:16:22.204] Timer tick, asking Buildbucket for the build status
[22:16:22.325] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:22.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:108:0) after 1m0s
[22:17:22.363] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:108:0)
[22:17:22.363] Timer tick, asking Buildbucket for the build status
[22:17:22.473] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:22.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:109:0) after 1m0s
[22:18:22.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:109:0)
[22:18:22.496] Timer tick, asking Buildbucket for the build status
[22:18:22.720] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:22.720] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:110:0) after 1m0s
[22:19:22.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:110:0)
[22:19:22.742] Timer tick, asking Buildbucket for the build status
[22:19:22.816] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:22.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:111:0) after 1m0s
[22:20:22.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:111:0)
[22:20:22.837] Timer tick, asking Buildbucket for the build status
[22:20:22.915] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:22.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:112:0) after 1m0s
[22:21:22.947] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:112:0)
[22:21:22.947] Timer tick, asking Buildbucket for the build status
[22:21:23.357] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:23.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:113:0) after 1m0s
[22:22:23.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:113:0)
[22:22:23.374] Timer tick, asking Buildbucket for the build status
[22:22:23.439] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:23.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:114:0) after 1m0s
[22:23:23.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:114:0)
[22:23:23.465] Timer tick, asking Buildbucket for the build status
[22:23:23.502] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:23.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:115:0) after 1m0s
[22:24:23.528] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:115:0)
[22:24:23.528] Timer tick, asking Buildbucket for the build status
[22:24:23.587] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:23.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:116:0) after 1m0s
[22:25:23.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:116:0)
[22:25:23.610] Timer tick, asking Buildbucket for the build status
[22:25:23.734] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:23.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:117:0) after 1m0s
[22:26:23.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:117:0)
[22:26:23.715] Timer tick, asking Buildbucket for the build status
[22:26:23.797] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:23.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:118:0) after 1m0s
[22:27:23.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:118:0)
[22:27:23.820] Timer tick, asking Buildbucket for the build status
[22:27:23.933] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:23.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:119:0) after 1m0s
[22:28:23.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:119:0)
[22:28:23.953] Timer tick, asking Buildbucket for the build status
[22:28:23.988] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:23.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:120:0) after 1m0s
[22:29:24.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:120:0)
[22:29:24.015] Timer tick, asking Buildbucket for the build status
[22:29:24.075] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:24.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:121:0) after 1m0s
[22:30:24.098] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:121:0)
[22:30:24.098] Timer tick, asking Buildbucket for the build status
[22:30:24.138] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:24.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:122:0) after 1m0s
[22:31:24.187] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:122:0)
[22:31:24.187] Timer tick, asking Buildbucket for the build status
[22:31:24.245] Build 8914902513491341552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:24.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9080332575697224768:123:0) after 1m0s
[22:31:43.385] Received PubSub notification, asking Buildbucket for the build status
[22:31:43.467] Build 8914902513491341552: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:31:43.467] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1556490703085806",
"created_by": "project:fuchsia",
"created_ts": "1556483461825863",
"failure_reason": "BUILD_FAILURE",
"id": "8914902513491341552",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"85bacd0f957887049f1cd782477e4064b29f4558\"}}",
"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-003729e9-us-central1-b-jtdk\", \"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\": \"85bacd0f957887049f1cd782477e4064b29f4558\", \"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-003729e9-us-central1-b-jtdk\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190426-c90e1d5-prod\"], \"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\": [\"4274-a0a5f8c\"], \"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": "1556483480968301",
"status": "COMPLETED",
"status_changed_ts": "1556483482197225",
"tags": [
"builder:clang",
"buildset:commit/git/85bacd0f957887049f1cd782477e4064b29f4558",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/85bacd0f957887049f1cd782477e4064b29f4558",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080332575697224768",
"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/8914902513491341552/+/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:447e765e8f4d5610",
"user_agent:luci-scheduler"
],
"updated_ts": "1556490703142179",
"url": "https://ci.chromium.org/b/8914902513491341552",
"utcnow_ts": "1556490703455693"
}
}
[22:31:43.467] Invocation finished in 2h0m43.129494693s with status FAILED