[22:17:04.108] New invocation is queued and will start shortly
[22:17:05.165] Starting the invocation (attempt 1)
[22:17:05.208] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:17:05.208] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:17:05.208] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9079872917985048160",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"99f8c4f5b659ab92c8eafc6dc9f887f1af465eb0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9079872917985048160",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/99f8c4f5b659ab92c8eafc6dc9f887f1af465eb0",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/99f8c4f5b659ab92c8eafc6dc9f887f1af465eb0",
"gitiles_ref:refs/heads/master"
]
}
[22:17:05.853] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1556921825347701",
"id": "8914442856023074400",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"99f8c4f5b659ab92c8eafc6dc9f887f1af465eb0\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1556921825724065",
"tags": [
"builder:clang",
"buildset:commit/git/99f8c4f5b659ab92c8eafc6dc9f887f1af465eb0",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/99f8c4f5b659ab92c8eafc6dc9f887f1af465eb0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079872917985048160",
"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/8914442856023074400/+/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": "1556921825726049",
"url": "https://ci.chromium.org/b/8914442856023074400",
"utcnow_ts": "1556921825845176"
}
}
[22:17:05.853] Task URL: https://ci.chromium.org/b/8914442856023074400
[22:17:05.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:2:0) after 1m0s
[22:17:24.407] Received PubSub notification, asking Buildbucket for the build status
[22:17:24.462] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:05.879] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:2:0)
[22:18:05.879] Timer tick, asking Buildbucket for the build status
[22:18:06.487] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:06.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:4:0) after 1m0s
[22:19:06.461] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:4:0)
[22:19:06.461] Timer tick, asking Buildbucket for the build status
[22:19:07.006] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:07.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:5:0) after 1m0s
[22:20:07.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:5:0)
[22:20:07.029] Timer tick, asking Buildbucket for the build status
[22:20:07.110] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:07.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:6:0) after 1m0s
[22:21:07.134] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:6:0)
[22:21:07.134] Timer tick, asking Buildbucket for the build status
[22:21:07.357] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:07.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:7:0) after 1m0s
[22:22:07.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:7:0)
[22:22:07.379] Timer tick, asking Buildbucket for the build status
[22:22:07.425] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:07.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:8:0) after 1m0s
[22:23:07.412] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:8:0)
[22:23:07.412] Timer tick, asking Buildbucket for the build status
[22:23:07.488] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:07.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:9:0) after 1m0s
[22:24:07.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:9:0)
[22:24:07.507] Timer tick, asking Buildbucket for the build status
[22:24:07.579] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:07.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:10:0) after 1m0s
[22:25:07.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:10:0)
[22:25:07.600] Timer tick, asking Buildbucket for the build status
[22:25:07.694] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:07.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:11:0) after 1m0s
[22:26:07.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:11:0)
[22:26:07.710] Timer tick, asking Buildbucket for the build status
[22:26:08.153] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:08.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:12:0) after 1m0s
[22:27:08.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:12:0)
[22:27:08.191] Timer tick, asking Buildbucket for the build status
[22:27:08.246] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:08.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:13:0) after 1m0s
[22:28:08.242] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:13:0)
[22:28:08.242] Timer tick, asking Buildbucket for the build status
[22:28:08.304] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:08.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:14:0) after 1m0s
[22:29:08.323] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:14:0)
[22:29:08.323] Timer tick, asking Buildbucket for the build status
[22:29:08.388] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:08.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:15:0) after 1m0s
[22:30:08.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:15:0)
[22:30:08.397] Timer tick, asking Buildbucket for the build status
[22:30:08.556] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:08.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:16:0) after 1m0s
[22:31:08.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:16:0)
[22:31:08.534] Timer tick, asking Buildbucket for the build status
[22:31:08.618] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:08.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:17:0) after 1m0s
[22:32:08.639] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:17:0)
[22:32:08.639] Timer tick, asking Buildbucket for the build status
[22:32:08.714] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:08.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:18:0) after 1m0s
[22:33:08.715] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:18:0)
[22:33:08.715] Timer tick, asking Buildbucket for the build status
[22:33:08.766] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:08.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:19:0) after 1m0s
[22:34:08.765] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:19:0)
[22:34:08.765] Timer tick, asking Buildbucket for the build status
[22:34:08.896] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:08.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:20:0) after 1m0s
[22:35:08.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:20:0)
[22:35:08.919] Timer tick, asking Buildbucket for the build status
[22:35:09.331] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:09.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:21:0) after 1m0s
[22:36:09.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:21:0)
[22:36:09.359] Timer tick, asking Buildbucket for the build status
[22:36:09.437] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:09.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:22:0) after 1m0s
[22:37:09.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:22:0)
[22:37:09.432] Timer tick, asking Buildbucket for the build status
[22:37:09.894] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:09.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:23:0) after 1m0s
[22:38:09.919] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:23:0)
[22:38:09.919] Timer tick, asking Buildbucket for the build status
[22:38:10.008] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:10.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:24:0) after 1m0s
[22:39:10.033] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:24:0)
[22:39:10.033] Timer tick, asking Buildbucket for the build status
[22:39:10.109] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:10.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:25:0) after 1m0s
[22:40:10.121] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:25:0)
[22:40:10.121] Timer tick, asking Buildbucket for the build status
[22:40:10.327] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:10.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:26:0) after 1m0s
[22:41:10.348] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:26:0)
[22:41:10.348] Timer tick, asking Buildbucket for the build status
[22:41:10.534] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:10.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:27:0) after 1m0s
[22:42:10.554] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:27:0)
[22:42:10.554] Timer tick, asking Buildbucket for the build status
[22:42:10.603] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:10.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:28:0) after 1m0s
[22:43:10.622] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:28:0)
[22:43:10.622] Timer tick, asking Buildbucket for the build status
[22:43:10.769] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:10.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:29:0) after 1m0s
[22:44:10.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:29:0)
[22:44:10.778] Timer tick, asking Buildbucket for the build status
[22:44:10.942] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:10.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:30:0) after 1m0s
[22:45:10.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:30:0)
[22:45:10.971] Timer tick, asking Buildbucket for the build status
[22:45:11.036] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:11.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:31:0) after 1m0s
[22:46:11.022] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:31:0)
[22:46:11.023] Timer tick, asking Buildbucket for the build status
[22:46:11.154] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:11.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:32:0) after 1m0s
[22:47:11.175] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:32:0)
[22:47:11.175] Timer tick, asking Buildbucket for the build status
[22:47:11.212] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:11.212] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:33:0) after 1m0s
[22:48:11.233] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:33:0)
[22:48:11.233] Timer tick, asking Buildbucket for the build status
[22:48:11.335] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:11.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:34:0) after 1m0s
[22:49:11.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:34:0)
[22:49:11.318] Timer tick, asking Buildbucket for the build status
[22:49:11.443] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:11.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:35:0) after 1m0s
[22:50:11.452] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:35:0)
[22:50:11.452] Timer tick, asking Buildbucket for the build status
[22:50:11.744] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:11.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:36:0) after 1m0s
[22:51:11.733] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:36:0)
[22:51:11.733] Timer tick, asking Buildbucket for the build status
[22:51:11.882] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:11.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:37:0) after 1m0s
[22:52:11.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:37:0)
[22:52:11.916] Timer tick, asking Buildbucket for the build status
[22:52:12.195] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:12.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:38:0) after 1m0s
[22:53:12.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:38:0)
[22:53:12.207] Timer tick, asking Buildbucket for the build status
[22:53:12.278] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:12.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:39:0) after 1m0s
[22:54:12.289] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:39:0)
[22:54:12.289] Timer tick, asking Buildbucket for the build status
[22:54:12.398] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:12.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:40:0) after 1m0s
[22:55:12.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:40:0)
[22:55:12.405] Timer tick, asking Buildbucket for the build status
[22:55:12.738] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:12.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:41:0) after 1m0s
[22:56:12.758] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:41:0)
[22:56:12.758] Timer tick, asking Buildbucket for the build status
[22:56:12.867] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:12.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:42:0) after 1m0s
[22:57:12.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:42:0)
[22:57:12.886] Timer tick, asking Buildbucket for the build status
[22:57:12.944] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:12.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:43:0) after 1m0s
[22:58:12.966] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:43:0)
[22:58:12.966] Timer tick, asking Buildbucket for the build status
[22:58:13.021] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:13.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:44:0) after 1m0s
[22:59:13.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:44:0)
[22:59:13.044] Timer tick, asking Buildbucket for the build status
[22:59:13.152] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:13.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:45:0) after 1m0s
[23:00:13.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:45:0)
[23:00:13.185] Timer tick, asking Buildbucket for the build status
[23:00:13.747] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:13.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:46:0) after 1m0s
[23:01:14.004] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:46:0)
[23:01:14.004] Timer tick, asking Buildbucket for the build status
[23:01:14.204] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:14.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:47:0) after 1m0s
[23:02:14.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:47:0)
[23:02:14.203] Timer tick, asking Buildbucket for the build status
[23:02:14.323] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:14.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:48:0) after 1m0s
[23:03:14.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:48:0)
[23:03:14.318] Timer tick, asking Buildbucket for the build status
[23:03:14.448] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:14.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:49:0) after 1m0s
[23:04:14.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:49:0)
[23:04:14.438] Timer tick, asking Buildbucket for the build status
[23:04:14.556] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:14.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:50:0) after 1m0s
[23:05:14.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:50:0)
[23:05:14.577] Timer tick, asking Buildbucket for the build status
[23:05:14.774] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:14.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:51:0) after 1m0s
[23:06:14.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:51:0)
[23:06:14.768] Timer tick, asking Buildbucket for the build status
[23:06:15.095] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:15.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:52:0) after 1m0s
[23:07:15.100] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:52:0)
[23:07:15.100] Timer tick, asking Buildbucket for the build status
[23:07:15.208] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:15.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:53:0) after 1m0s
[23:08:15.227] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:53:0)
[23:08:15.227] Timer tick, asking Buildbucket for the build status
[23:08:15.279] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:15.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:54:0) after 1m0s
[23:09:15.276] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:54:0)
[23:09:15.276] Timer tick, asking Buildbucket for the build status
[23:09:15.386] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:15.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:55:0) after 1m0s
[23:10:15.408] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:55:0)
[23:10:15.408] Timer tick, asking Buildbucket for the build status
[23:10:15.666] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:15.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:56:0) after 1m0s
[23:11:15.638] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:56:0)
[23:11:15.638] Timer tick, asking Buildbucket for the build status
[23:11:15.746] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:15.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:57:0) after 1m0s
[23:12:15.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:57:0)
[23:12:15.767] Timer tick, asking Buildbucket for the build status
[23:12:15.921] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:15.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:58:0) after 1m0s
[23:13:15.943] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:58:0)
[23:13:15.943] Timer tick, asking Buildbucket for the build status
[23:13:16.203] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:16.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:59:0) after 1m0s
[23:14:16.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:59:0)
[23:14:16.251] Timer tick, asking Buildbucket for the build status
[23:14:16.320] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:16.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:60:0) after 1m0s
[23:15:16.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:60:0)
[23:15:16.300] Timer tick, asking Buildbucket for the build status
[23:15:16.388] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:16.388] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:61:0) after 1m0s
[23:16:16.411] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:61:0)
[23:16:16.411] Timer tick, asking Buildbucket for the build status
[23:16:16.701] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:16.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:62:0) after 1m0s
[23:17:16.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:62:0)
[23:17:16.720] Timer tick, asking Buildbucket for the build status
[23:17:16.784] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:16.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:63:0) after 1m0s
[23:18:16.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:63:0)
[23:18:16.812] Timer tick, asking Buildbucket for the build status
[23:18:16.877] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:16.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:64:0) after 1m0s
[23:19:16.899] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:64:0)
[23:19:16.899] Timer tick, asking Buildbucket for the build status
[23:19:17.006] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:17.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:65:0) after 1m0s
[23:20:17.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:65:0)
[23:20:17.034] Timer tick, asking Buildbucket for the build status
[23:20:17.101] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:17.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:66:0) after 1m0s
[23:21:17.125] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:66:0)
[23:21:17.125] Timer tick, asking Buildbucket for the build status
[23:21:17.195] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:17.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:67:0) after 1m0s
[23:22:17.223] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:67:0)
[23:22:17.223] Timer tick, asking Buildbucket for the build status
[23:22:17.616] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:17.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:68:0) after 1m0s
[23:23:17.641] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:68:0)
[23:23:17.641] Timer tick, asking Buildbucket for the build status
[23:23:17.741] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:17.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:69:0) after 1m0s
[23:24:17.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:69:0)
[23:24:17.749] Timer tick, asking Buildbucket for the build status
[23:24:17.812] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:17.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:70:0) after 1m0s
[23:25:17.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:70:0)
[23:25:17.831] Timer tick, asking Buildbucket for the build status
[23:25:17.904] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:17.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:71:0) after 1m0s
[23:26:17.924] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:71:0)
[23:26:17.924] Timer tick, asking Buildbucket for the build status
[23:26:17.964] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:17.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:72:0) after 1m0s
[23:27:17.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:72:0)
[23:27:17.987] Timer tick, asking Buildbucket for the build status
[23:27:18.080] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:18.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:73:0) after 1m0s
[23:28:18.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:73:0)
[23:28:18.092] Timer tick, asking Buildbucket for the build status
[23:28:18.249] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:18.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:74:0) after 1m0s
[23:29:18.277] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:74:0)
[23:29:18.277] Timer tick, asking Buildbucket for the build status
[23:29:18.370] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:18.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:75:0) after 1m0s
[23:30:18.396] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:75:0)
[23:30:18.396] Timer tick, asking Buildbucket for the build status
[23:30:18.545] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:18.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:76:0) after 1m0s
[23:31:18.565] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:76:0)
[23:31:18.565] Timer tick, asking Buildbucket for the build status
[23:31:18.683] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:18.683] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:77:0) after 1m0s
[23:32:18.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:77:0)
[23:32:18.674] Timer tick, asking Buildbucket for the build status
[23:32:18.795] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:18.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:78:0) after 1m0s
[23:33:18.816] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:78:0)
[23:33:18.816] Timer tick, asking Buildbucket for the build status
[23:33:19.260] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:19.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:79:0) after 1m0s
[23:34:19.259] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:79:0)
[23:34:19.259] Timer tick, asking Buildbucket for the build status
[23:34:19.335] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:19.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:80:0) after 1m0s
[23:35:19.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:80:0)
[23:35:19.338] Timer tick, asking Buildbucket for the build status
[23:35:19.446] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:19.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:81:0) after 1m0s
[23:36:19.466] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:81:0)
[23:36:19.466] Timer tick, asking Buildbucket for the build status
[23:36:19.593] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:19.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:82:0) after 1m0s
[23:37:19.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:82:0)
[23:37:19.616] Timer tick, asking Buildbucket for the build status
[23:37:19.742] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:19.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:83:0) after 1m0s
[23:38:19.823] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:83:0)
[23:38:19.823] Timer tick, asking Buildbucket for the build status
[23:38:19.988] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:19.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:84:0) after 1m0s
[23:39:19.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:84:0)
[23:39:19.993] Timer tick, asking Buildbucket for the build status
[23:39:20.054] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:20.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:85:0) after 1m0s
[23:40:20.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:85:0)
[23:40:20.077] Timer tick, asking Buildbucket for the build status
[23:40:20.155] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:20.155] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:86:0) after 1m0s
[23:41:20.175] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:86:0)
[23:41:20.175] Timer tick, asking Buildbucket for the build status
[23:41:20.336] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:20.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:87:0) after 1m0s
[23:42:20.354] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:87:0)
[23:42:20.354] Timer tick, asking Buildbucket for the build status
[23:42:20.496] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:20.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:88:0) after 1m0s
[23:43:20.522] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:88:0)
[23:43:20.522] Timer tick, asking Buildbucket for the build status
[23:43:20.608] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:20.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:89:0) after 1m0s
[23:44:20.629] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:89:0)
[23:44:20.629] Timer tick, asking Buildbucket for the build status
[23:44:20.689] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:20.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:90:0) after 1m0s
[23:45:20.708] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:90:0)
[23:45:20.708] Timer tick, asking Buildbucket for the build status
[23:45:20.842] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:20.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:91:0) after 1m0s
[23:46:20.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:91:0)
[23:46:20.852] Timer tick, asking Buildbucket for the build status
[23:46:21.635] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:21.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:92:0) after 1m0s
[23:47:21.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:92:0)
[23:47:21.692] Timer tick, asking Buildbucket for the build status
[23:47:21.746] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:21.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:93:0) after 1m0s
[23:48:21.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:93:0)
[23:48:21.749] Timer tick, asking Buildbucket for the build status
[23:48:21.840] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:21.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:94:0) after 1m0s
[23:49:21.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:94:0)
[23:49:21.862] Timer tick, asking Buildbucket for the build status
[23:49:21.949] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:21.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:95:0) after 1m0s
[23:50:21.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:95:0)
[23:50:21.976] Timer tick, asking Buildbucket for the build status
[23:50:22.043] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:22.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:96:0) after 1m0s
[23:51:22.072] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:96:0)
[23:51:22.072] Timer tick, asking Buildbucket for the build status
[23:51:22.129] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:22.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:97:0) after 1m0s
[23:52:22.127] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:97:0)
[23:52:22.127] Timer tick, asking Buildbucket for the build status
[23:52:22.187] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:22.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:98:0) after 1m0s
[23:53:22.168] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:98:0)
[23:53:22.168] Timer tick, asking Buildbucket for the build status
[23:53:22.231] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:22.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:99:0) after 1m0s
[23:54:22.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:99:0)
[23:54:22.246] Timer tick, asking Buildbucket for the build status
[23:54:22.452] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:22.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:100:0) after 1m0s
[23:55:22.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:100:0)
[23:55:22.433] Timer tick, asking Buildbucket for the build status
[23:55:22.522] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:22.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:101:0) after 1m0s
[23:56:22.548] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:101:0)
[23:56:22.548] Timer tick, asking Buildbucket for the build status
[23:56:22.836] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:22.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:102:0) after 1m0s
[23:57:22.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:102:0)
[23:57:22.880] Timer tick, asking Buildbucket for the build status
[23:57:22.923] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:22.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:103:0) after 1m0s
[23:58:22.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:103:0)
[23:58:22.945] Timer tick, asking Buildbucket for the build status
[23:58:23.056] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:23.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:104:0) after 1m0s
[23:59:23.080] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:104:0)
[23:59:23.080] Timer tick, asking Buildbucket for the build status
[23:59:23.263] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:23.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:105:0) after 1m0s
[00:00:23.287] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:105:0)
[00:00:23.287] Timer tick, asking Buildbucket for the build status
[00:00:23.338] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:23.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:106:0) after 1m0s
[00:01:26.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:106:0)
[00:01:26.734] Timer tick, asking Buildbucket for the build status
[00:01:27.290] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:27.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:107:0) after 1m0s
[00:02:27.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:107:0)
[00:02:27.309] Timer tick, asking Buildbucket for the build status
[00:02:27.748] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:27.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:108:0) after 1m0s
[00:03:27.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:108:0)
[00:03:27.768] Timer tick, asking Buildbucket for the build status
[00:03:27.833] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:27.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:109:0) after 1m0s
[00:04:27.893] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:109:0)
[00:04:27.893] Timer tick, asking Buildbucket for the build status
[00:04:27.943] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:27.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:110:0) after 1m0s
[00:05:27.963] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:110:0)
[00:05:27.963] Timer tick, asking Buildbucket for the build status
[00:05:28.359] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:28.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:111:0) after 1m0s
[00:06:28.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:111:0)
[00:06:28.386] Timer tick, asking Buildbucket for the build status
[00:06:28.458] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:28.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:112:0) after 1m0s
[00:07:28.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:112:0)
[00:07:28.496] Timer tick, asking Buildbucket for the build status
[00:07:28.986] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:28.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:113:0) after 1m0s
[00:08:29.082] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:113:0)
[00:08:29.082] Timer tick, asking Buildbucket for the build status
[00:08:29.148] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:29.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:114:0) after 1m0s
[00:09:29.152] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:114:0)
[00:09:29.152] Timer tick, asking Buildbucket for the build status
[00:09:29.329] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:29.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:115:0) after 1m0s
[00:10:29.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:115:0)
[00:10:29.351] Timer tick, asking Buildbucket for the build status
[00:10:29.761] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:29.761] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:116:0) after 1m0s
[00:11:29.786] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:116:0)
[00:11:29.786] Timer tick, asking Buildbucket for the build status
[00:11:30.145] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:30.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:117:0) after 1m0s
[00:12:30.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:117:0)
[00:12:30.136] Timer tick, asking Buildbucket for the build status
[00:12:30.988] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:30.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:118:0) after 1m0s
[00:13:31.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:118:0)
[00:13:31.015] Timer tick, asking Buildbucket for the build status
[00:13:31.797] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:31.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:119:0) after 1m0s
[00:14:31.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:119:0)
[00:14:31.827] Timer tick, asking Buildbucket for the build status
[00:14:31.885] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:31.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:120:0) after 1m0s
[00:15:31.930] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:120:0)
[00:15:31.930] Timer tick, asking Buildbucket for the build status
[00:15:32.366] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:32.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:121:0) after 1m0s
[00:16:32.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:121:0)
[00:16:32.407] Timer tick, asking Buildbucket for the build status
[00:16:32.480] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:32.480] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:122:0) after 1m0s
[00:17:32.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:122:0)
[00:17:32.463] Timer tick, asking Buildbucket for the build status
[00:17:32.576] Build 8914442856023074400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:32.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9079872917985048160:123:0) after 1m0s
[00:17:54.472] Received PubSub notification, asking Buildbucket for the build status
[00:17:54.633] Build 8914442856023074400: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:17:54.633] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1556929072896566",
"created_by": "project:fuchsia",
"created_ts": "1556921825347701",
"failure_reason": "BUILD_FAILURE",
"id": "8914442856023074400",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"99f8c4f5b659ab92c8eafc6dc9f887f1af465eb0\"}}",
"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-c-m648\", \"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\": \"99f8c4f5b659ab92c8eafc6dc9f887f1af465eb0\", \"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-c-m648\"], \"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\": [\"4284-fd5e65b\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1556921842787959",
"status": "COMPLETED",
"status_changed_ts": "1556921844014697",
"tags": [
"builder:clang",
"buildset:commit/git/99f8c4f5b659ab92c8eafc6dc9f887f1af465eb0",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/99f8c4f5b659ab92c8eafc6dc9f887f1af465eb0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079872917985048160",
"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/8914442856023074400/+/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:4498974316d6fc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1556929073610339",
"url": "https://ci.chromium.org/b/8914442856023074400",
"utcnow_ts": "1556929074616636"
}
}
[00:17:54.633] Invocation finished in 2h0m50.541126295s with status FAILED