[15:44:28.147] New invocation is queued and will start shortly
[15:44:29.252] Starting the invocation (attempt 1)
[15:44:29.292] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:44:29.292] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:44:29.292] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078901051570667520",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"e041d15f5e326f7ee0b1ea9c7a988f8965ce7e6a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078901051570667520",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/e041d15f5e326f7ee0b1ea9c7a988f8965ce7e6a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/e041d15f5e326f7ee0b1ea9c7a988f8965ce7e6a",
"gitiles_ref:refs/heads/master"
]
}
[15:44:30.536] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557848669457229",
"id": "8913470989533615040",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"e041d15f5e326f7ee0b1ea9c7a988f8965ce7e6a\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557848670321072",
"tags": [
"builder:clang",
"buildset:commit/git/e041d15f5e326f7ee0b1ea9c7a988f8965ce7e6a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/e041d15f5e326f7ee0b1ea9c7a988f8965ce7e6a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078901051570667520",
"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/8913470989533615040/+/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": "1557848670321260",
"url": "https://ci.chromium.org/b/8913470989533615040",
"utcnow_ts": "1557848670483067"
}
}
[15:44:30.536] Task URL: https://ci.chromium.org/b/8913470989533615040
[15:44:30.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:2:0) after 1m0s
[15:44:38.490] Received PubSub notification, asking Buildbucket for the build status
[15:44:38.557] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:30.519] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:2:0)
[15:45:30.519] Timer tick, asking Buildbucket for the build status
[15:45:30.876] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:30.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:4:0) after 1m0s
[15:46:30.846] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:4:0)
[15:46:30.846] Timer tick, asking Buildbucket for the build status
[15:46:30.916] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:30.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:5:0) after 1m0s
[15:47:30.951] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:5:0)
[15:47:30.951] Timer tick, asking Buildbucket for the build status
[15:47:31.059] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:31.059] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:6:0) after 1m0s
[15:48:31.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:6:0)
[15:48:31.084] Timer tick, asking Buildbucket for the build status
[15:48:31.317] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:31.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:7:0) after 1m0s
[15:49:31.320] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:7:0)
[15:49:31.320] Timer tick, asking Buildbucket for the build status
[15:49:31.412] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:31.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:8:0) after 1m0s
[15:50:31.465] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:8:0)
[15:50:31.465] Timer tick, asking Buildbucket for the build status
[15:50:31.531] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:31.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:9:0) after 1m0s
[15:51:31.758] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:9:0)
[15:51:31.758] Timer tick, asking Buildbucket for the build status
[15:51:32.932] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:32.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:10:0) after 1m0s
[15:52:33.321] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:10:0)
[15:52:33.321] Timer tick, asking Buildbucket for the build status
[15:52:33.385] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:33.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:11:0) after 1m0s
[15:53:33.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:11:0)
[15:53:33.398] Timer tick, asking Buildbucket for the build status
[15:53:33.477] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:33.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:12:0) after 1m0s
[15:54:33.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:12:0)
[15:54:33.606] Timer tick, asking Buildbucket for the build status
[15:54:33.790] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:33.790] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:13:0) after 1m0s
[15:55:34.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:13:0)
[15:55:34.701] Timer tick, asking Buildbucket for the build status
[15:55:34.745] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:34.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:14:0) after 1m0s
[15:56:34.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:14:0)
[15:56:34.769] Timer tick, asking Buildbucket for the build status
[15:56:34.896] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:34.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:15:0) after 1m0s
[15:57:35.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:15:0)
[15:57:35.091] Timer tick, asking Buildbucket for the build status
[15:57:35.176] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:35.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:16:0) after 1m0s
[15:58:35.194] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:16:0)
[15:58:35.194] Timer tick, asking Buildbucket for the build status
[15:58:35.254] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:35.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:17:0) after 1m0s
[15:59:35.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:17:0)
[15:59:35.274] Timer tick, asking Buildbucket for the build status
[15:59:35.772] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:35.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:18:0) after 1m0s
[16:00:35.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:18:0)
[16:00:35.806] Timer tick, asking Buildbucket for the build status
[16:00:35.872] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:35.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:19:0) after 1m0s
[16:01:35.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:19:0)
[16:01:35.902] Timer tick, asking Buildbucket for the build status
[16:01:36.300] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:36.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:20:0) after 1m0s
[16:02:36.366] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:20:0)
[16:02:36.366] Timer tick, asking Buildbucket for the build status
[16:02:36.531] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:36.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:21:0) after 1m0s
[16:03:36.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:21:0)
[16:03:36.599] Timer tick, asking Buildbucket for the build status
[16:03:36.787] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:36.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:22:0) after 1m0s
[16:04:36.807] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:22:0)
[16:04:36.807] Timer tick, asking Buildbucket for the build status
[16:04:37.158] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:37.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:23:0) after 1m0s
[16:05:37.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:23:0)
[16:05:37.199] Timer tick, asking Buildbucket for the build status
[16:05:37.350] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:37.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:24:0) after 1m0s
[16:06:37.338] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:24:0)
[16:06:37.338] Timer tick, asking Buildbucket for the build status
[16:06:37.831] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:37.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:25:0) after 1m0s
[16:07:37.851] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:25:0)
[16:07:37.851] Timer tick, asking Buildbucket for the build status
[16:07:38.280] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:38.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:26:0) after 1m0s
[16:08:38.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:26:0)
[16:08:38.300] Timer tick, asking Buildbucket for the build status
[16:08:38.951] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:38.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:27:0) after 1m0s
[16:09:39.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:27:0)
[16:09:39.016] Timer tick, asking Buildbucket for the build status
[16:09:39.429] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:39.429] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:28:0) after 1m0s
[16:10:39.450] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:28:0)
[16:10:39.450] Timer tick, asking Buildbucket for the build status
[16:10:39.564] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:39.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:29:0) after 1m0s
[16:11:39.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:29:0)
[16:11:39.533] Timer tick, asking Buildbucket for the build status
[16:11:39.595] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:39.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:30:0) after 1m0s
[16:12:39.612] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:30:0)
[16:12:39.612] Timer tick, asking Buildbucket for the build status
[16:12:39.669] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:39.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:31:0) after 1m0s
[16:13:39.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:31:0)
[16:13:39.719] Timer tick, asking Buildbucket for the build status
[16:13:39.793] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:39.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:32:0) after 1m0s
[16:14:39.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:32:0)
[16:14:39.813] Timer tick, asking Buildbucket for the build status
[16:14:39.986] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:39.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:33:0) after 1m0s
[16:15:40.008] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:33:0)
[16:15:40.008] Timer tick, asking Buildbucket for the build status
[16:15:40.182] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:40.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:34:0) after 1m0s
[16:16:40.224] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:34:0)
[16:16:40.224] Timer tick, asking Buildbucket for the build status
[16:16:40.570] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:40.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:35:0) after 1m0s
[16:17:40.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:35:0)
[16:17:40.596] Timer tick, asking Buildbucket for the build status
[16:17:40.966] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:40.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:36:0) after 1m0s
[16:18:40.974] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:36:0)
[16:18:40.974] Timer tick, asking Buildbucket for the build status
[16:18:41.205] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:41.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:37:0) after 1m0s
[16:19:41.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:37:0)
[16:19:41.567] Timer tick, asking Buildbucket for the build status
[16:19:41.627] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:41.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:38:0) after 1m0s
[16:20:41.658] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:38:0)
[16:20:41.658] Timer tick, asking Buildbucket for the build status
[16:20:41.782] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:41.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:39:0) after 1m0s
[16:21:41.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:39:0)
[16:21:41.821] Timer tick, asking Buildbucket for the build status
[16:21:41.882] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:41.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:40:0) after 1m0s
[16:22:41.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:40:0)
[16:22:41.912] Timer tick, asking Buildbucket for the build status
[16:22:41.991] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:41.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:41:0) after 1m0s
[16:23:42.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:41:0)
[16:23:42.019] Timer tick, asking Buildbucket for the build status
[16:23:42.148] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:42.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:42:0) after 1m0s
[16:24:42.177] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:42:0)
[16:24:42.177] Timer tick, asking Buildbucket for the build status
[16:24:42.373] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:42.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:43:0) after 1m0s
[16:25:42.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:43:0)
[16:25:42.395] Timer tick, asking Buildbucket for the build status
[16:25:42.437] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:42.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:44:0) after 1m0s
[16:26:45.554] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:44:0)
[16:26:45.554] Timer tick, asking Buildbucket for the build status
[16:26:45.960] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:45.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:45:0) after 1m0s
[16:27:45.986] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:45:0)
[16:27:45.986] Timer tick, asking Buildbucket for the build status
[16:27:46.102] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:46.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:46:0) after 1m0s
[16:28:46.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:46:0)
[16:28:46.107] Timer tick, asking Buildbucket for the build status
[16:28:46.209] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:46.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:47:0) after 1m0s
[16:29:46.217] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:47:0)
[16:29:46.217] Timer tick, asking Buildbucket for the build status
[16:29:46.263] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:46.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:48:0) after 1m0s
[16:30:46.328] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:48:0)
[16:30:46.328] Timer tick, asking Buildbucket for the build status
[16:30:46.557] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:46.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:49:0) after 1m0s
[16:31:46.821] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:49:0)
[16:31:46.821] Timer tick, asking Buildbucket for the build status
[16:31:46.877] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:46.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:50:0) after 1m0s
[16:32:46.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:50:0)
[16:32:46.889] Timer tick, asking Buildbucket for the build status
[16:32:46.960] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:46.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:51:0) after 1m0s
[16:33:47.021] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:51:0)
[16:33:47.021] Timer tick, asking Buildbucket for the build status
[16:33:47.153] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:47.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:52:0) after 1m0s
[16:34:47.256] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:52:0)
[16:34:47.256] Timer tick, asking Buildbucket for the build status
[16:34:47.373] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:47.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:53:0) after 1m0s
[16:35:47.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:53:0)
[16:35:47.407] Timer tick, asking Buildbucket for the build status
[16:35:48.457] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:48.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:54:0) after 1m0s
[16:36:48.446] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:54:0)
[16:36:48.446] Timer tick, asking Buildbucket for the build status
[16:36:48.620] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:48.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:55:0) after 1m0s
[16:37:48.613] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:55:0)
[16:37:48.613] Timer tick, asking Buildbucket for the build status
[16:37:48.771] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:48.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:56:0) after 1m0s
[16:38:48.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:56:0)
[16:38:48.801] Timer tick, asking Buildbucket for the build status
[16:38:49.319] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:49.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:57:0) after 1m0s
[16:39:49.342] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:57:0)
[16:39:49.342] Timer tick, asking Buildbucket for the build status
[16:39:49.516] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:49.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:58:0) after 1m0s
[16:40:49.523] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:58:0)
[16:40:49.523] Timer tick, asking Buildbucket for the build status
[16:40:49.896] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:49.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:59:0) after 1m0s
[16:41:49.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:59:0)
[16:41:49.920] Timer tick, asking Buildbucket for the build status
[16:41:50.099] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:50.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:60:0) after 1m0s
[16:42:50.102] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:60:0)
[16:42:50.102] Timer tick, asking Buildbucket for the build status
[16:42:50.163] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:50.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:61:0) after 1m0s
[16:43:50.213] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:61:0)
[16:43:50.213] Timer tick, asking Buildbucket for the build status
[16:43:50.396] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:50.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:62:0) after 1m0s
[16:44:50.423] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:62:0)
[16:44:50.423] Timer tick, asking Buildbucket for the build status
[16:44:50.748] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:50.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:63:0) after 1m0s
[16:45:50.767] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:63:0)
[16:45:50.767] Timer tick, asking Buildbucket for the build status
[16:45:51.267] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:51.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:64:0) after 1m0s
[16:46:51.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:64:0)
[16:46:51.283] Timer tick, asking Buildbucket for the build status
[16:46:51.344] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:51.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:65:0) after 1m0s
[16:47:51.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:65:0)
[16:47:51.351] Timer tick, asking Buildbucket for the build status
[16:47:51.395] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:51.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:66:0) after 1m0s
[16:48:51.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:66:0)
[16:48:51.383] Timer tick, asking Buildbucket for the build status
[16:48:51.440] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:51.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:67:0) after 1m0s
[16:49:51.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:67:0)
[16:49:51.463] Timer tick, asking Buildbucket for the build status
[16:49:51.551] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:51.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:68:0) after 1m0s
[16:50:51.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:68:0)
[16:50:51.559] Timer tick, asking Buildbucket for the build status
[16:50:51.931] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:51.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:69:0) after 1m0s
[16:51:51.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:69:0)
[16:51:51.956] Timer tick, asking Buildbucket for the build status
[16:51:52.004] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:52.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:70:0) after 1m0s
[16:52:52.001] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:70:0)
[16:52:52.001] Timer tick, asking Buildbucket for the build status
[16:52:52.073] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:52.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:71:0) after 1m0s
[16:53:52.116] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:71:0)
[16:53:52.116] Timer tick, asking Buildbucket for the build status
[16:53:52.219] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:52.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:72:0) after 1m0s
[16:54:52.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:72:0)
[16:54:52.245] Timer tick, asking Buildbucket for the build status
[16:54:52.286] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:52.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:73:0) after 1m0s
[16:55:52.278] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:73:0)
[16:55:52.278] Timer tick, asking Buildbucket for the build status
[16:55:52.368] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:52.368] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:74:0) after 1m0s
[16:56:52.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:74:0)
[16:56:52.395] Timer tick, asking Buildbucket for the build status
[16:56:52.440] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:52.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:75:0) after 1m0s
[16:57:52.463] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:75:0)
[16:57:52.463] Timer tick, asking Buildbucket for the build status
[16:57:52.557] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:52.557] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:76:0) after 1m0s
[16:58:52.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:76:0)
[16:58:52.598] Timer tick, asking Buildbucket for the build status
[16:58:52.769] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:52.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:77:0) after 1m0s
[16:59:52.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:77:0)
[16:59:52.794] Timer tick, asking Buildbucket for the build status
[16:59:52.944] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:52.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:78:0) after 1m0s
[17:00:52.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:78:0)
[17:00:52.967] Timer tick, asking Buildbucket for the build status
[17:00:53.100] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:53.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:79:0) after 1m0s
[17:01:53.122] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:79:0)
[17:01:53.122] Timer tick, asking Buildbucket for the build status
[17:01:53.283] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:53.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:80:0) after 1m0s
[17:02:53.325] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:80:0)
[17:02:53.325] Timer tick, asking Buildbucket for the build status
[17:02:53.362] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:53.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:81:0) after 1m0s
[17:03:53.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:81:0)
[17:03:53.389] Timer tick, asking Buildbucket for the build status
[17:03:53.510] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:53.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:82:0) after 1m0s
[17:04:53.584] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:82:0)
[17:04:53.584] Timer tick, asking Buildbucket for the build status
[17:04:53.934] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:53.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:83:0) after 1m0s
[17:05:53.956] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:83:0)
[17:05:53.956] Timer tick, asking Buildbucket for the build status
[17:05:54.002] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:54.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:84:0) after 1m0s
[17:06:53.992] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:84:0)
[17:06:53.992] Timer tick, asking Buildbucket for the build status
[17:06:54.076] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:54.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:85:0) after 1m0s
[17:07:54.153] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:85:0)
[17:07:54.153] Timer tick, asking Buildbucket for the build status
[17:07:54.255] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:54.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:86:0) after 1m0s
[17:08:54.282] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:86:0)
[17:08:54.282] Timer tick, asking Buildbucket for the build status
[17:08:54.350] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:54.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:87:0) after 1m0s
[17:09:54.388] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:87:0)
[17:09:54.388] Timer tick, asking Buildbucket for the build status
[17:09:54.614] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:54.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:88:0) after 1m0s
[17:10:54.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:88:0)
[17:10:54.674] Timer tick, asking Buildbucket for the build status
[17:10:54.727] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:54.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:89:0) after 1m0s
[17:11:54.849] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:89:0)
[17:11:54.849] Timer tick, asking Buildbucket for the build status
[17:11:55.216] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:55.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:90:0) after 1m0s
[17:12:55.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:90:0)
[17:12:55.204] Timer tick, asking Buildbucket for the build status
[17:12:55.273] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:55.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:91:0) after 1m0s
[17:13:55.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:91:0)
[17:13:55.395] Timer tick, asking Buildbucket for the build status
[17:13:55.564] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:55.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:92:0) after 1m0s
[17:14:55.574] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:92:0)
[17:14:55.574] Timer tick, asking Buildbucket for the build status
[17:14:55.627] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:55.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:93:0) after 1m0s
[17:15:55.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:93:0)
[17:15:55.648] Timer tick, asking Buildbucket for the build status
[17:15:55.693] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:55.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:94:0) after 1m0s
[17:16:55.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:94:0)
[17:16:55.725] Timer tick, asking Buildbucket for the build status
[17:16:55.938] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:55.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:95:0) after 1m0s
[17:17:55.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:95:0)
[17:17:55.976] Timer tick, asking Buildbucket for the build status
[17:17:56.161] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:56.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:96:0) after 1m0s
[17:18:56.147] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:96:0)
[17:18:56.147] Timer tick, asking Buildbucket for the build status
[17:18:56.308] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:56.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:97:0) after 1m0s
[17:19:56.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:97:0)
[17:19:56.332] Timer tick, asking Buildbucket for the build status
[17:19:56.383] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:56.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:98:0) after 1m0s
[17:20:56.410] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:98:0)
[17:20:56.410] Timer tick, asking Buildbucket for the build status
[17:20:56.528] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:56.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:99:0) after 1m0s
[17:21:56.560] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:99:0)
[17:21:56.560] Timer tick, asking Buildbucket for the build status
[17:21:57.322] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:57.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:100:0) after 1m0s
[17:22:57.361] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:100:0)
[17:22:57.361] Timer tick, asking Buildbucket for the build status
[17:22:57.502] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:57.502] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:101:0) after 1m0s
[17:23:57.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:101:0)
[17:23:57.481] Timer tick, asking Buildbucket for the build status
[17:23:57.834] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:57.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:102:0) after 1m0s
[17:24:57.843] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:102:0)
[17:24:57.843] Timer tick, asking Buildbucket for the build status
[17:24:57.936] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:57.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:103:0) after 1m0s
[17:25:57.988] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:103:0)
[17:25:57.988] Timer tick, asking Buildbucket for the build status
[17:25:58.197] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:58.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:104:0) after 1m0s
[17:26:58.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:104:0)
[17:26:58.185] Timer tick, asking Buildbucket for the build status
[17:26:58.456] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:58.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:105:0) after 1m0s
[17:27:58.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:105:0)
[17:27:58.480] Timer tick, asking Buildbucket for the build status
[17:27:58.521] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:58.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:106:0) after 1m0s
[17:28:58.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:106:0)
[17:28:58.569] Timer tick, asking Buildbucket for the build status
[17:28:58.771] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:58.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:107:0) after 1m0s
[17:29:58.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:107:0)
[17:29:58.798] Timer tick, asking Buildbucket for the build status
[17:29:58.861] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:58.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:108:0) after 1m0s
[17:31:01.316] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:108:0)
[17:31:01.316] Timer tick, asking Buildbucket for the build status
[17:31:01.568] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:01.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:109:0) after 1m0s
[17:32:01.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:109:0)
[17:32:01.618] Timer tick, asking Buildbucket for the build status
[17:32:02.641] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:02.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:110:0) after 1m0s
[17:33:02.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:110:0)
[17:33:02.642] Timer tick, asking Buildbucket for the build status
[17:33:02.860] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:02.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:111:0) after 1m0s
[17:34:02.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:111:0)
[17:34:02.902] Timer tick, asking Buildbucket for the build status
[17:34:03.367] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:03.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:112:0) after 1m0s
[17:35:03.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:112:0)
[17:35:03.399] Timer tick, asking Buildbucket for the build status
[17:35:03.512] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:03.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:113:0) after 1m0s
[17:36:03.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:113:0)
[17:36:03.530] Timer tick, asking Buildbucket for the build status
[17:36:03.737] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:03.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:114:0) after 1m0s
[17:37:03.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:114:0)
[17:37:03.770] Timer tick, asking Buildbucket for the build status
[17:37:04.134] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:04.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:115:0) after 1m0s
[17:38:04.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:115:0)
[17:38:04.193] Timer tick, asking Buildbucket for the build status
[17:38:04.247] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:04.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:116:0) after 1m0s
[17:39:04.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:116:0)
[17:39:04.330] Timer tick, asking Buildbucket for the build status
[17:39:04.491] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:04.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:117:0) after 1m0s
[17:40:04.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:117:0)
[17:40:04.530] Timer tick, asking Buildbucket for the build status
[17:40:06.998] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:06.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:118:0) after 1m0s
[17:41:06.982] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:118:0)
[17:41:06.982] Timer tick, asking Buildbucket for the build status
[17:41:07.159] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:07.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:119:0) after 1m0s
[17:42:07.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:119:0)
[17:42:07.198] Timer tick, asking Buildbucket for the build status
[17:42:07.310] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:07.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:120:0) after 1m0s
[17:43:07.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:120:0)
[17:43:07.332] Timer tick, asking Buildbucket for the build status
[17:43:07.409] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:07.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:121:0) after 1m0s
[17:44:07.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:121:0)
[17:44:07.432] Timer tick, asking Buildbucket for the build status
[17:44:07.752] Build 8913470989533615040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:07.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078901051570667520:122:0) after 1m0s
[17:45:01.550] Received PubSub notification, asking Buildbucket for the build status
[17:45:01.804] Build 8913470989533615040: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:45:01.805] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557855900903006",
"created_by": "project:fuchsia",
"created_ts": "1557848669457229",
"failure_reason": "BUILD_FAILURE",
"id": "8913470989533615040",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"e041d15f5e326f7ee0b1ea9c7a988f8965ce7e6a\"}}",
"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-b-1-5mek\", \"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\": \"e041d15f5e326f7ee0b1ea9c7a988f8965ce7e6a\", \"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-b-1-5mek\"], \"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\": [\"4293-d3520ea\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1557848677066114",
"status": "COMPLETED",
"status_changed_ts": "1557848677956115",
"tags": [
"builder:clang",
"buildset:commit/git/e041d15f5e326f7ee0b1ea9c7a988f8965ce7e6a",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/e041d15f5e326f7ee0b1ea9c7a988f8965ce7e6a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078901051570667520",
"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/8913470989533615040/+/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:44cfd5cbd3ab1c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1557855900981437",
"url": "https://ci.chromium.org/b/8913470989533615040",
"utcnow_ts": "1557855901765689"
}
}
[17:45:01.805] Invocation finished in 2h0m33.674059888s with status FAILED