[23:52:44.850] New invocation is queued and will start shortly
[23:52:48.187] Starting the invocation (attempt 1)
[23:52:48.232] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:52:48.232] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:52:48.232] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078326749979913536",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"1a5cc629debd8c0b6741b587d533868836c55718\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078326749979913536",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/1a5cc629debd8c0b6741b587d533868836c55718",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1a5cc629debd8c0b6741b587d533868836c55718",
"gitiles_ref:refs/heads/master"
]
}
[23:52:49.194] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558396368355018",
"id": "8912896685614301696",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"1a5cc629debd8c0b6741b587d533868836c55718\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558396368815977",
"tags": [
"builder:clang",
"buildset:commit/git/1a5cc629debd8c0b6741b587d533868836c55718",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1a5cc629debd8c0b6741b587d533868836c55718",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078326749979913536",
"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/8912896685614301696/+/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": "1558396368816221",
"url": "https://ci.chromium.org/b/8912896685614301696",
"utcnow_ts": "1558396369185991"
}
}
[23:52:49.194] Task URL: https://ci.chromium.org/b/8912896685614301696
[23:52:49.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:2:0) after 1m0s
[23:52:55.153] Received PubSub notification, asking Buildbucket for the build status
[23:52:55.311] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:49.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:2:0)
[23:53:49.210] Timer tick, asking Buildbucket for the build status
[23:53:49.366] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:49.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:4:0) after 1m0s
[23:54:49.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:4:0)
[23:54:49.386] Timer tick, asking Buildbucket for the build status
[23:54:49.492] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:49.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:5:0) after 1m0s
[23:55:49.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:5:0)
[23:55:49.507] Timer tick, asking Buildbucket for the build status
[23:55:49.750] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:49.750] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:6:0) after 1m0s
[23:56:49.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:6:0)
[23:56:49.781] Timer tick, asking Buildbucket for the build status
[23:56:50.672] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:50.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:7:0) after 1m0s
[23:57:50.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:7:0)
[23:57:50.677] Timer tick, asking Buildbucket for the build status
[23:57:51.305] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:51.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:8:0) after 1m0s
[23:58:51.337] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:8:0)
[23:58:51.337] Timer tick, asking Buildbucket for the build status
[23:58:51.401] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:51.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:9:0) after 1m0s
[23:59:51.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:9:0)
[23:59:51.439] Timer tick, asking Buildbucket for the build status
[23:59:51.507] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:51.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:10:0) after 1m0s
[00:00:51.537] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:10:0)
[00:00:51.537] Timer tick, asking Buildbucket for the build status
[00:00:51.570] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:51.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:11:0) after 1m0s
[00:01:51.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:11:0)
[00:01:51.611] Timer tick, asking Buildbucket for the build status
[00:01:51.717] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:51.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:12:0) after 1m0s
[00:02:51.714] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:12:0)
[00:02:51.714] Timer tick, asking Buildbucket for the build status
[00:02:51.926] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:51.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:13:0) after 1m0s
[00:03:51.960] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:13:0)
[00:03:51.960] Timer tick, asking Buildbucket for the build status
[00:03:52.062] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:52.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:14:0) after 1m0s
[00:04:52.112] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:14:0)
[00:04:52.112] Timer tick, asking Buildbucket for the build status
[00:04:52.499] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:52.499] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:15:0) after 1m0s
[00:05:52.532] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:15:0)
[00:05:52.532] Timer tick, asking Buildbucket for the build status
[00:05:52.660] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:52.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:16:0) after 1m0s
[00:06:52.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:16:0)
[00:06:52.656] Timer tick, asking Buildbucket for the build status
[00:06:52.714] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:52.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:17:0) after 1m0s
[00:07:52.742] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:17:0)
[00:07:52.742] Timer tick, asking Buildbucket for the build status
[00:07:53.182] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:53.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:18:0) after 1m0s
[00:08:53.263] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:18:0)
[00:08:53.263] Timer tick, asking Buildbucket for the build status
[00:08:53.477] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:53.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:19:0) after 1m0s
[00:09:53.499] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:19:0)
[00:09:53.499] Timer tick, asking Buildbucket for the build status
[00:09:53.553] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:53.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:20:0) after 1m0s
[00:10:53.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:20:0)
[00:10:53.580] Timer tick, asking Buildbucket for the build status
[00:10:53.760] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:53.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:21:0) after 1m0s
[00:11:53.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:21:0)
[00:11:53.749] Timer tick, asking Buildbucket for the build status
[00:11:53.792] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:53.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:22:0) after 1m0s
[00:12:53.927] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:22:0)
[00:12:53.927] Timer tick, asking Buildbucket for the build status
[00:12:54.030] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:54.030] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:23:0) after 1m0s
[00:13:54.073] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:23:0)
[00:13:54.073] Timer tick, asking Buildbucket for the build status
[00:13:54.213] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:54.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:24:0) after 1m0s
[00:14:54.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:24:0)
[00:14:54.242] Timer tick, asking Buildbucket for the build status
[00:14:54.429] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:54.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:25:0) after 1m0s
[00:15:54.455] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:25:0)
[00:15:54.455] Timer tick, asking Buildbucket for the build status
[00:15:54.824] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:54.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:26:0) after 1m0s
[00:16:54.861] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:26:0)
[00:16:54.861] Timer tick, asking Buildbucket for the build status
[00:16:54.917] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:54.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:27:0) after 1m0s
[00:17:54.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:27:0)
[00:17:54.986] Timer tick, asking Buildbucket for the build status
[00:17:55.103] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:55.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:28:0) after 1m0s
[00:18:55.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:28:0)
[00:18:55.077] Timer tick, asking Buildbucket for the build status
[00:18:55.145] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:55.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:29:0) after 1m0s
[00:19:55.175] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:29:0)
[00:19:55.175] Timer tick, asking Buildbucket for the build status
[00:19:55.267] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:55.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:30:0) after 1m0s
[00:20:55.296] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:30:0)
[00:20:55.296] Timer tick, asking Buildbucket for the build status
[00:20:55.602] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:55.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:31:0) after 1m0s
[00:21:55.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:31:0)
[00:21:55.612] Timer tick, asking Buildbucket for the build status
[00:21:55.975] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:55.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:32:0) after 1m0s
[00:22:56.031] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:32:0)
[00:22:56.031] Timer tick, asking Buildbucket for the build status
[00:22:56.207] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:56.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:33:0) after 1m0s
[00:23:56.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:33:0)
[00:23:56.203] Timer tick, asking Buildbucket for the build status
[00:23:56.702] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:56.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:34:0) after 1m0s
[00:24:56.692] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:34:0)
[00:24:56.692] Timer tick, asking Buildbucket for the build status
[00:24:56.849] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:56.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:35:0) after 1m0s
[00:25:56.859] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:35:0)
[00:25:56.859] Timer tick, asking Buildbucket for the build status
[00:25:56.927] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:56.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:36:0) after 1m0s
[00:26:56.953] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:36:0)
[00:26:56.953] Timer tick, asking Buildbucket for the build status
[00:26:57.092] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:57.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:37:0) after 1m0s
[00:27:57.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:37:0)
[00:27:57.314] Timer tick, asking Buildbucket for the build status
[00:27:57.492] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:57.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:38:0) after 1m0s
[00:28:57.508] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:38:0)
[00:28:57.508] Timer tick, asking Buildbucket for the build status
[00:28:57.677] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:57.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:39:0) after 1m0s
[00:29:57.714] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:39:0)
[00:29:57.714] Timer tick, asking Buildbucket for the build status
[00:29:57.814] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:57.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:40:0) after 1m0s
[00:30:57.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:40:0)
[00:30:57.833] Timer tick, asking Buildbucket for the build status
[00:30:57.983] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:57.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:41:0) after 1m0s
[00:31:57.997] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:41:0)
[00:31:57.997] Timer tick, asking Buildbucket for the build status
[00:31:58.104] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:58.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:42:0) after 1m0s
[00:32:58.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:42:0)
[00:32:58.189] Timer tick, asking Buildbucket for the build status
[00:32:58.252] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:58.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:43:0) after 1m0s
[00:33:58.281] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:43:0)
[00:33:58.281] Timer tick, asking Buildbucket for the build status
[00:33:58.386] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:58.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:44:0) after 1m0s
[00:34:58.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:44:0)
[00:34:58.407] Timer tick, asking Buildbucket for the build status
[00:34:58.545] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:58.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:45:0) after 1m0s
[00:35:58.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:45:0)
[00:35:58.523] Timer tick, asking Buildbucket for the build status
[00:35:58.606] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:58.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:46:0) after 1m0s
[00:36:58.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:46:0)
[00:36:58.719] Timer tick, asking Buildbucket for the build status
[00:36:58.792] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:58.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:47:0) after 1m0s
[00:37:58.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:47:0)
[00:37:58.828] Timer tick, asking Buildbucket for the build status
[00:37:58.916] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:58.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:48:0) after 1m0s
[00:38:58.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:48:0)
[00:38:58.938] Timer tick, asking Buildbucket for the build status
[00:38:59.555] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:59.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:49:0) after 1m0s
[00:39:59.604] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:49:0)
[00:39:59.604] Timer tick, asking Buildbucket for the build status
[00:39:59.972] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:59.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:50:0) after 1m0s
[00:41:00.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:50:0)
[00:41:00.029] Timer tick, asking Buildbucket for the build status
[00:41:00.111] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:00.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:51:0) after 1m0s
[00:42:00.132] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:51:0)
[00:42:00.132] Timer tick, asking Buildbucket for the build status
[00:42:00.421] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:00.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:52:0) after 1m0s
[00:43:00.527] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:52:0)
[00:43:00.527] Timer tick, asking Buildbucket for the build status
[00:43:00.927] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:00.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:53:0) after 1m0s
[00:44:01.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:53:0)
[00:44:01.131] Timer tick, asking Buildbucket for the build status
[00:44:01.295] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:01.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:54:0) after 1m0s
[00:45:01.341] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:54:0)
[00:45:01.341] Timer tick, asking Buildbucket for the build status
[00:45:01.508] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:01.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:55:0) after 1m0s
[00:46:01.513] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:55:0)
[00:46:01.513] Timer tick, asking Buildbucket for the build status
[00:46:01.672] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:01.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:56:0) after 1m0s
[00:47:01.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:56:0)
[00:47:01.703] Timer tick, asking Buildbucket for the build status
[00:47:01.830] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:01.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:57:0) after 1m0s
[00:48:01.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:57:0)
[00:48:01.826] Timer tick, asking Buildbucket for the build status
[00:48:02.266] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:02.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:58:0) after 1m0s
[00:49:02.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:58:0)
[00:49:02.301] Timer tick, asking Buildbucket for the build status
[00:49:02.944] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:02.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:59:0) after 1m0s
[00:50:02.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:59:0)
[00:50:02.976] Timer tick, asking Buildbucket for the build status
[00:50:03.049] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:03.049] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:60:0) after 1m0s
[00:51:03.071] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:60:0)
[00:51:03.071] Timer tick, asking Buildbucket for the build status
[00:51:03.159] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:03.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:61:0) after 1m0s
[00:52:03.182] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:61:0)
[00:52:03.182] Timer tick, asking Buildbucket for the build status
[00:52:03.429] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:03.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:62:0) after 1m0s
[00:53:03.473] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:62:0)
[00:53:03.473] Timer tick, asking Buildbucket for the build status
[00:53:03.619] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:03.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:63:0) after 1m0s
[00:54:03.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:63:0)
[00:54:03.644] Timer tick, asking Buildbucket for the build status
[00:54:03.703] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:03.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:64:0) after 1m0s
[00:55:03.741] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:64:0)
[00:55:03.741] Timer tick, asking Buildbucket for the build status
[00:55:03.820] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:03.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:65:0) after 1m0s
[00:56:03.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:65:0)
[00:56:03.822] Timer tick, asking Buildbucket for the build status
[00:56:04.135] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:04.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:66:0) after 1m0s
[00:57:04.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:66:0)
[00:57:04.131] Timer tick, asking Buildbucket for the build status
[00:57:07.482] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:07.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:67:0) after 1m0s
[00:58:07.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:67:0)
[00:58:07.507] Timer tick, asking Buildbucket for the build status
[00:58:07.573] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:07.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:68:0) after 1m0s
[00:59:07.638] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:68:0)
[00:59:07.638] Timer tick, asking Buildbucket for the build status
[00:59:07.701] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:07.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:69:0) after 1m0s
[01:00:07.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:69:0)
[01:00:07.701] Timer tick, asking Buildbucket for the build status
[01:00:07.746] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:07.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:70:0) after 1m0s
[01:01:07.766] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:70:0)
[01:01:07.766] Timer tick, asking Buildbucket for the build status
[01:01:07.848] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:07.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:71:0) after 1m0s
[01:02:07.873] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:71:0)
[01:02:07.873] Timer tick, asking Buildbucket for the build status
[01:02:08.098] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:08.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:72:0) after 1m0s
[01:03:08.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:72:0)
[01:03:08.077] Timer tick, asking Buildbucket for the build status
[01:03:08.127] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:08.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:73:0) after 1m0s
[01:04:08.208] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:73:0)
[01:04:08.208] Timer tick, asking Buildbucket for the build status
[01:04:08.257] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:08.257] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:74:0) after 1m0s
[01:05:08.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:74:0)
[01:05:08.302] Timer tick, asking Buildbucket for the build status
[01:05:08.417] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:08.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:75:0) after 1m0s
[01:06:08.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:75:0)
[01:06:08.410] Timer tick, asking Buildbucket for the build status
[01:06:08.478] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:08.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:76:0) after 1m0s
[01:07:08.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:76:0)
[01:07:08.465] Timer tick, asking Buildbucket for the build status
[01:07:08.634] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:08.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:77:0) after 1m0s
[01:08:08.659] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:77:0)
[01:08:08.659] Timer tick, asking Buildbucket for the build status
[01:08:09.173] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:09.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:78:0) after 1m0s
[01:09:09.210] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:78:0)
[01:09:09.210] Timer tick, asking Buildbucket for the build status
[01:09:09.262] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:09.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:79:0) after 1m0s
[01:10:09.288] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:79:0)
[01:10:09.288] Timer tick, asking Buildbucket for the build status
[01:10:10.343] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:10.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:80:0) after 1m0s
[01:11:10.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:80:0)
[01:11:10.389] Timer tick, asking Buildbucket for the build status
[01:11:10.485] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:10.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:81:0) after 1m0s
[01:12:10.509] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:81:0)
[01:12:10.509] Timer tick, asking Buildbucket for the build status
[01:12:10.600] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:10.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:82:0) after 1m0s
[01:13:10.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:82:0)
[01:13:10.720] Timer tick, asking Buildbucket for the build status
[01:13:11.062] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:11.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:83:0) after 1m0s
[01:14:11.094] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:83:0)
[01:14:11.094] Timer tick, asking Buildbucket for the build status
[01:14:11.312] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:11.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:84:0) after 1m0s
[01:15:11.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:84:0)
[01:15:11.332] Timer tick, asking Buildbucket for the build status
[01:15:11.405] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:11.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:85:0) after 1m0s
[01:16:11.454] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:85:0)
[01:16:11.454] Timer tick, asking Buildbucket for the build status
[01:16:11.769] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:11.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:86:0) after 1m0s
[01:17:11.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:86:0)
[01:17:11.803] Timer tick, asking Buildbucket for the build status
[01:17:11.873] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:11.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:87:0) after 1m0s
[01:18:12.081] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:87:0)
[01:18:12.081] Timer tick, asking Buildbucket for the build status
[01:18:12.201] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:12.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:88:0) after 1m0s
[01:19:12.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:88:0)
[01:19:12.228] Timer tick, asking Buildbucket for the build status
[01:19:12.335] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:12.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:89:0) after 1m0s
[01:20:12.324] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:89:0)
[01:20:12.324] Timer tick, asking Buildbucket for the build status
[01:20:12.521] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:12.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:90:0) after 1m0s
[01:21:12.611] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:90:0)
[01:21:12.611] Timer tick, asking Buildbucket for the build status
[01:21:12.716] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:12.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:91:0) after 1m0s
[01:22:12.706] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:91:0)
[01:22:12.706] Timer tick, asking Buildbucket for the build status
[01:22:13.506] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:13.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:92:0) after 1m0s
[01:23:13.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:92:0)
[01:23:13.517] Timer tick, asking Buildbucket for the build status
[01:23:13.659] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:13.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:93:0) after 1m0s
[01:24:13.687] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:93:0)
[01:24:13.687] Timer tick, asking Buildbucket for the build status
[01:24:13.812] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:13.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:94:0) after 1m0s
[01:25:13.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:94:0)
[01:25:13.806] Timer tick, asking Buildbucket for the build status
[01:25:14.222] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:14.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:95:0) after 1m0s
[01:26:14.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:95:0)
[01:26:14.218] Timer tick, asking Buildbucket for the build status
[01:26:14.401] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:14.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:96:0) after 1m0s
[01:27:14.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:96:0)
[01:27:14.431] Timer tick, asking Buildbucket for the build status
[01:27:14.596] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:14.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:97:0) after 1m0s
[01:28:14.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:97:0)
[01:28:14.619] Timer tick, asking Buildbucket for the build status
[01:28:14.882] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:14.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:98:0) after 1m0s
[01:29:14.886] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:98:0)
[01:29:14.886] Timer tick, asking Buildbucket for the build status
[01:29:14.977] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:14.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:99:0) after 1m0s
[01:30:15.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:99:0)
[01:30:15.008] Timer tick, asking Buildbucket for the build status
[01:30:15.072] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:15.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:100:0) after 1m0s
[01:31:15.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:100:0)
[01:31:15.107] Timer tick, asking Buildbucket for the build status
[01:31:15.481] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:15.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:101:0) after 1m0s
[01:32:15.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:101:0)
[01:32:15.493] Timer tick, asking Buildbucket for the build status
[01:32:15.938] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:15.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:102:0) after 1m0s
[01:33:15.946] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:102:0)
[01:33:15.946] Timer tick, asking Buildbucket for the build status
[01:33:16.019] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:16.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:103:0) after 1m0s
[01:34:16.041] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:103:0)
[01:34:16.041] Timer tick, asking Buildbucket for the build status
[01:34:16.081] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:16.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:104:0) after 1m0s
[01:35:16.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:104:0)
[01:35:16.169] Timer tick, asking Buildbucket for the build status
[01:35:16.258] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:16.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:105:0) after 1m0s
[01:36:16.285] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:105:0)
[01:36:16.285] Timer tick, asking Buildbucket for the build status
[01:36:16.379] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:16.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:106:0) after 1m0s
[01:37:16.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:106:0)
[01:37:16.399] Timer tick, asking Buildbucket for the build status
[01:37:16.588] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:16.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:107:0) after 1m0s
[01:38:16.620] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:107:0)
[01:38:16.620] Timer tick, asking Buildbucket for the build status
[01:38:16.732] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:16.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:108:0) after 1m0s
[01:39:16.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:108:0)
[01:39:16.792] Timer tick, asking Buildbucket for the build status
[01:39:16.870] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:16.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:109:0) after 1m0s
[01:40:16.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:109:0)
[01:40:16.896] Timer tick, asking Buildbucket for the build status
[01:40:17.074] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:17.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:110:0) after 1m0s
[01:41:17.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:110:0)
[01:41:17.149] Timer tick, asking Buildbucket for the build status
[01:41:17.267] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:17.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:111:0) after 1m0s
[01:42:17.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:111:0)
[01:42:17.265] Timer tick, asking Buildbucket for the build status
[01:42:17.463] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:17.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:112:0) after 1m0s
[01:43:17.510] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:112:0)
[01:43:17.510] Timer tick, asking Buildbucket for the build status
[01:43:17.847] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:17.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:113:0) after 1m0s
[01:44:17.834] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:113:0)
[01:44:17.834] Timer tick, asking Buildbucket for the build status
[01:44:17.943] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:17.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:114:0) after 1m0s
[01:45:18.675] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:114:0)
[01:45:18.675] Timer tick, asking Buildbucket for the build status
[01:45:18.838] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:18.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:115:0) after 1m0s
[01:46:18.856] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:115:0)
[01:46:18.856] Timer tick, asking Buildbucket for the build status
[01:46:19.207] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:19.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:116:0) after 1m0s
[01:47:19.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:116:0)
[01:47:19.191] Timer tick, asking Buildbucket for the build status
[01:47:19.263] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:19.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:117:0) after 1m0s
[01:48:19.302] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:117:0)
[01:48:19.302] Timer tick, asking Buildbucket for the build status
[01:48:19.346] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:19.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:118:0) after 1m0s
[01:49:19.396] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:118:0)
[01:49:19.396] Timer tick, asking Buildbucket for the build status
[01:49:19.527] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:19.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:119:0) after 1m0s
[01:50:19.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:119:0)
[01:50:19.542] Timer tick, asking Buildbucket for the build status
[01:50:19.669] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:19.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:120:0) after 1m0s
[01:51:19.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:120:0)
[01:51:19.768] Timer tick, asking Buildbucket for the build status
[01:51:19.829] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:19.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:121:0) after 1m0s
[01:52:19.855] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:121:0)
[01:52:19.855] Timer tick, asking Buildbucket for the build status
[01:52:19.933] Build 8912896685614301696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:19.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078326749979913536:122:0) after 1m0s
[01:53:17.965] Received PubSub notification, asking Buildbucket for the build status
[01:53:18.185] Build 8912896685614301696: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:53:18.185] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558403597627991",
"created_by": "project:fuchsia",
"created_ts": "1558396368355018",
"failure_reason": "BUILD_FAILURE",
"id": "8912896685614301696",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"1a5cc629debd8c0b6741b587d533868836c55718\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-c-2-x115\", \"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\": \"1a5cc629debd8c0b6741b587d533868836c55718\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-2-x115\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4302-e8210c4\"], \"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": "1558396374199607",
"status": "COMPLETED",
"status_changed_ts": "1558396374895300",
"tags": [
"builder:clang",
"buildset:commit/git/1a5cc629debd8c0b6741b587d533868836c55718",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/1a5cc629debd8c0b6741b587d533868836c55718",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078326749979913536",
"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/8912896685614301696/+/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:44f07b01b86b6210",
"user_agent:luci-scheduler"
],
"updated_ts": "1558403597723426",
"url": "https://ci.chromium.org/b/8912896685614301696",
"utcnow_ts": "1558403598173775"
}
}
[01:53:18.185] Invocation finished in 2h0m33.377281509s with status FAILED