[22:41:38.717] New invocation is queued and will start shortly
[22:41:40.022] Starting the invocation (attempt 1)
[22:41:40.055] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:41:40.055] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:41:40.055] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9077787641521467696",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"e698958ad8031e0f17202e06f5de53989852bb66\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9077787641521467696",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/e698958ad8031e0f17202e06f5de53989852bb66",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/e698958ad8031e0f17202e06f5de53989852bb66",
"gitiles_ref:refs/heads/master"
]
}
[22:41:40.587] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558910500160348",
"id": "8912357579342718368",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"e698958ad8031e0f17202e06f5de53989852bb66\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558910500383086",
"tags": [
"builder:clang",
"buildset:commit/git/e698958ad8031e0f17202e06f5de53989852bb66",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/e698958ad8031e0f17202e06f5de53989852bb66",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077787641521467696",
"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/8912357579342718368/+/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": "1558910500383172",
"url": "https://ci.chromium.org/b/8912357579342718368",
"utcnow_ts": "1558910500530522"
}
}
[22:41:40.587] Task URL: https://ci.chromium.org/b/8912357579342718368
[22:41:40.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:2:0) after 1m0s
[22:41:54.068] Received PubSub notification, asking Buildbucket for the build status
[22:41:54.133] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:40.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:2:0)
[22:42:40.642] Timer tick, asking Buildbucket for the build status
[22:42:40.735] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:40.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:4:0) after 1m0s
[22:43:40.938] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:4:0)
[22:43:40.938] Timer tick, asking Buildbucket for the build status
[22:43:41.019] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:41.019] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:5:0) after 1m0s
[22:44:41.350] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:5:0)
[22:44:41.350] Timer tick, asking Buildbucket for the build status
[22:44:41.452] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:41.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:6:0) after 1m0s
[22:45:41.585] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:6:0)
[22:45:41.585] Timer tick, asking Buildbucket for the build status
[22:45:41.818] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:41.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:7:0) after 1m0s
[22:46:41.882] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:7:0)
[22:46:41.882] Timer tick, asking Buildbucket for the build status
[22:46:42.006] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:42.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:8:0) after 1m0s
[22:47:42.037] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:8:0)
[22:47:42.037] Timer tick, asking Buildbucket for the build status
[22:47:42.089] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:42.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:9:0) after 1m0s
[22:48:42.107] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:9:0)
[22:48:42.107] Timer tick, asking Buildbucket for the build status
[22:48:42.248] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:42.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:10:0) after 1m0s
[22:49:42.285] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:10:0)
[22:49:42.285] Timer tick, asking Buildbucket for the build status
[22:49:42.364] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:42.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:11:0) after 1m0s
[22:50:42.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:11:0)
[22:50:42.383] Timer tick, asking Buildbucket for the build status
[22:50:42.474] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:42.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:12:0) after 1m0s
[22:51:42.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:12:0)
[22:51:42.596] Timer tick, asking Buildbucket for the build status
[22:51:42.636] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:42.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:13:0) after 1m0s
[22:52:42.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:13:0)
[22:52:42.656] Timer tick, asking Buildbucket for the build status
[22:52:42.788] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:42.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:14:0) after 1m0s
[22:53:42.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:14:0)
[22:53:42.805] Timer tick, asking Buildbucket for the build status
[22:53:43.002] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:43.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:15:0) after 1m0s
[22:54:42.982] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:15:0)
[22:54:42.982] Timer tick, asking Buildbucket for the build status
[22:54:43.066] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:43.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:16:0) after 1m0s
[22:55:43.087] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:16:0)
[22:55:43.087] Timer tick, asking Buildbucket for the build status
[22:55:43.158] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:43.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:17:0) after 1m0s
[22:56:43.174] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:17:0)
[22:56:43.174] Timer tick, asking Buildbucket for the build status
[22:56:43.230] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:43.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:18:0) after 1m0s
[22:57:43.247] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:18:0)
[22:57:43.247] Timer tick, asking Buildbucket for the build status
[22:57:43.332] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:43.332] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:19:0) after 1m0s
[22:58:43.353] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:19:0)
[22:58:43.353] Timer tick, asking Buildbucket for the build status
[22:58:43.468] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:43.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:20:0) after 1m0s
[22:59:43.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:20:0)
[22:59:43.490] Timer tick, asking Buildbucket for the build status
[22:59:43.703] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:43.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:21:0) after 1m0s
[23:00:43.721] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:21:0)
[23:00:43.721] Timer tick, asking Buildbucket for the build status
[23:00:43.827] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:43.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:22:0) after 1m0s
[23:01:43.852] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:22:0)
[23:01:43.852] Timer tick, asking Buildbucket for the build status
[23:01:43.903] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:43.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:23:0) after 1m0s
[23:02:43.983] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:23:0)
[23:02:43.983] Timer tick, asking Buildbucket for the build status
[23:02:44.031] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:44.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:24:0) after 1m0s
[23:03:43.999] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:24:0)
[23:03:43.999] Timer tick, asking Buildbucket for the build status
[23:03:44.211] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:44.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:25:0) after 1m0s
[23:04:44.237] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:25:0)
[23:04:44.237] Timer tick, asking Buildbucket for the build status
[23:04:44.377] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:44.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:26:0) after 1m0s
[23:05:44.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:26:0)
[23:05:44.498] Timer tick, asking Buildbucket for the build status
[23:05:44.559] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:44.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:27:0) after 1m0s
[23:06:45.391] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:27:0)
[23:06:45.391] Timer tick, asking Buildbucket for the build status
[23:06:45.580] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:45.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:28:0) after 1m0s
[23:07:45.646] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:28:0)
[23:07:45.646] Timer tick, asking Buildbucket for the build status
[23:07:45.727] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:45.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:29:0) after 1m0s
[23:08:45.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:29:0)
[23:08:45.772] Timer tick, asking Buildbucket for the build status
[23:08:46.121] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:46.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:30:0) after 1m0s
[23:09:46.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:30:0)
[23:09:46.149] Timer tick, asking Buildbucket for the build status
[23:09:46.215] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:46.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:31:0) after 1m0s
[23:10:46.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:31:0)
[23:10:46.222] Timer tick, asking Buildbucket for the build status
[23:10:46.393] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:46.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:32:0) after 1m0s
[23:11:46.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:32:0)
[23:11:46.380] Timer tick, asking Buildbucket for the build status
[23:11:46.512] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:46.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:33:0) after 1m0s
[23:12:46.534] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:33:0)
[23:12:46.534] Timer tick, asking Buildbucket for the build status
[23:12:46.725] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:46.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:34:0) after 1m0s
[23:13:46.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:34:0)
[23:13:46.804] Timer tick, asking Buildbucket for the build status
[23:13:47.036] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:47.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:35:0) after 1m0s
[23:14:47.063] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:35:0)
[23:14:47.063] Timer tick, asking Buildbucket for the build status
[23:14:47.511] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:47.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:36:0) after 1m0s
[23:15:47.514] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:36:0)
[23:15:47.514] Timer tick, asking Buildbucket for the build status
[23:15:47.724] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:47.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:37:0) after 1m0s
[23:16:47.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:37:0)
[23:16:47.773] Timer tick, asking Buildbucket for the build status
[23:16:47.939] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:47.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:38:0) after 1m0s
[23:17:48.058] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:38:0)
[23:17:48.059] Timer tick, asking Buildbucket for the build status
[23:17:48.153] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:48.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:39:0) after 1m0s
[23:18:48.176] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:39:0)
[23:18:48.176] Timer tick, asking Buildbucket for the build status
[23:18:48.266] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:48.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:40:0) after 1m0s
[23:19:48.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:40:0)
[23:19:48.286] Timer tick, asking Buildbucket for the build status
[23:19:48.341] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:48.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:41:0) after 1m0s
[23:20:48.409] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:41:0)
[23:20:48.409] Timer tick, asking Buildbucket for the build status
[23:20:48.526] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:48.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:42:0) after 1m0s
[23:21:48.559] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:42:0)
[23:21:48.559] Timer tick, asking Buildbucket for the build status
[23:21:48.722] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:48.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:43:0) after 1m0s
[23:22:48.884] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:43:0)
[23:22:48.884] Timer tick, asking Buildbucket for the build status
[23:22:49.042] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:49.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:44:0) after 1m0s
[23:23:49.130] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:44:0)
[23:23:49.130] Timer tick, asking Buildbucket for the build status
[23:23:49.242] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:49.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:45:0) after 1m0s
[23:24:49.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:45:0)
[23:24:49.511] Timer tick, asking Buildbucket for the build status
[23:24:49.602] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:49.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:46:0) after 1m0s
[23:25:49.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:46:0)
[23:25:49.689] Timer tick, asking Buildbucket for the build status
[23:25:50.362] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:50.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:47:0) after 1m0s
[23:26:50.437] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:47:0)
[23:26:50.437] Timer tick, asking Buildbucket for the build status
[23:26:51.702] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:51.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:48:0) after 1m0s
[23:27:51.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:48:0)
[23:27:51.888] Timer tick, asking Buildbucket for the build status
[23:27:52.203] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:52.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:49:0) after 1m0s
[23:28:52.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:49:0)
[23:28:52.359] Timer tick, asking Buildbucket for the build status
[23:28:52.568] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:52.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:50:0) after 1m0s
[23:29:52.666] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:50:0)
[23:29:52.666] Timer tick, asking Buildbucket for the build status
[23:29:52.838] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:52.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:51:0) after 1m0s
[23:30:52.935] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:51:0)
[23:30:52.935] Timer tick, asking Buildbucket for the build status
[23:30:53.025] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:53.025] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:52:0) after 1m0s
[23:31:53.020] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:52:0)
[23:31:53.020] Timer tick, asking Buildbucket for the build status
[23:31:53.526] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:53.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:53:0) after 1m0s
[23:32:53.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:53:0)
[23:32:53.614] Timer tick, asking Buildbucket for the build status
[23:32:53.719] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:53.720] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:54:0) after 1m0s
[23:33:54.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:54:0)
[23:33:54.083] Timer tick, asking Buildbucket for the build status
[23:33:54.358] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:54.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:55:0) after 1m0s
[23:34:56.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:55:0)
[23:34:56.392] Timer tick, asking Buildbucket for the build status
[23:34:57.146] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:57.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:56:0) after 1m0s
[23:35:57.226] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:56:0)
[23:35:57.226] Timer tick, asking Buildbucket for the build status
[23:35:57.409] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:57.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:57:0) after 1m0s
[23:36:57.428] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:57:0)
[23:36:57.428] Timer tick, asking Buildbucket for the build status
[23:36:57.692] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:57.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:58:0) after 1m0s
[23:37:57.725] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:58:0)
[23:37:57.725] Timer tick, asking Buildbucket for the build status
[23:37:58.458] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:58.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:59:0) after 1m0s
[23:38:58.568] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:59:0)
[23:38:58.568] Timer tick, asking Buildbucket for the build status
[23:38:58.735] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:58.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:60:0) after 1m0s
[23:39:58.758] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:60:0)
[23:39:58.758] Timer tick, asking Buildbucket for the build status
[23:39:58.875] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:58.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:61:0) after 1m0s
[23:40:58.908] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:61:0)
[23:40:58.908] Timer tick, asking Buildbucket for the build status
[23:40:58.958] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:58.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:62:0) after 1m0s
[23:41:58.981] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:62:0)
[23:41:58.981] Timer tick, asking Buildbucket for the build status
[23:41:59.125] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:59.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:63:0) after 1m0s
[23:42:59.139] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:63:0)
[23:42:59.139] Timer tick, asking Buildbucket for the build status
[23:42:59.245] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:59.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:64:0) after 1m0s
[23:43:59.596] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:64:0)
[23:43:59.596] Timer tick, asking Buildbucket for the build status
[23:43:59.703] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:59.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:65:0) after 1m0s
[23:45:00.050] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:65:0)
[23:45:00.050] Timer tick, asking Buildbucket for the build status
[23:45:00.641] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:00.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:66:0) after 1m0s
[23:46:00.892] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:66:0)
[23:46:00.892] Timer tick, asking Buildbucket for the build status
[23:46:01.011] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:01.011] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:67:0) after 1m0s
[23:47:01.333] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:67:0)
[23:47:01.333] Timer tick, asking Buildbucket for the build status
[23:47:01.753] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:01.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:68:0) after 1m0s
[23:48:01.874] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:68:0)
[23:48:01.874] Timer tick, asking Buildbucket for the build status
[23:48:02.273] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:02.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:69:0) after 1m0s
[23:49:02.246] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:69:0)
[23:49:02.246] Timer tick, asking Buildbucket for the build status
[23:49:02.359] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:02.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:70:0) after 1m0s
[23:50:02.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:70:0)
[23:50:02.387] Timer tick, asking Buildbucket for the build status
[23:50:02.445] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:02.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:71:0) after 1m0s
[23:51:02.464] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:71:0)
[23:51:02.464] Timer tick, asking Buildbucket for the build status
[23:51:02.920] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:02.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:72:0) after 1m0s
[23:52:02.940] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:72:0)
[23:52:02.940] Timer tick, asking Buildbucket for the build status
[23:52:05.133] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:05.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:73:0) after 1m0s
[23:53:05.198] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:73:0)
[23:53:05.198] Timer tick, asking Buildbucket for the build status
[23:53:05.635] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:05.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:74:0) after 1m0s
[23:54:05.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:74:0)
[23:54:05.696] Timer tick, asking Buildbucket for the build status
[23:54:06.633] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:06.633] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:75:0) after 1m0s
[23:55:06.656] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:75:0)
[23:55:06.656] Timer tick, asking Buildbucket for the build status
[23:55:06.776] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:06.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:76:0) after 1m0s
[23:56:06.801] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:76:0)
[23:56:06.801] Timer tick, asking Buildbucket for the build status
[23:56:07.187] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:07.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:77:0) after 1m0s
[23:57:07.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:77:0)
[23:57:07.209] Timer tick, asking Buildbucket for the build status
[23:57:07.385] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:07.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:78:0) after 1m0s
[23:58:07.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:78:0)
[23:58:07.471] Timer tick, asking Buildbucket for the build status
[23:58:07.674] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:07.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:79:0) after 1m0s
[23:59:07.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:79:0)
[23:59:07.719] Timer tick, asking Buildbucket for the build status
[23:59:07.783] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:07.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:80:0) after 1m0s
[00:00:07.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:80:0)
[00:00:07.805] Timer tick, asking Buildbucket for the build status
[00:00:07.901] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:07.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:81:0) after 1m0s
[00:01:07.987] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:81:0)
[00:01:07.987] Timer tick, asking Buildbucket for the build status
[00:01:08.453] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:08.453] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:82:0) after 1m0s
[00:02:08.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:82:0)
[00:02:08.598] Timer tick, asking Buildbucket for the build status
[00:02:09.501] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:09.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:83:0) after 1m0s
[00:03:09.573] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:83:0)
[00:03:09.573] Timer tick, asking Buildbucket for the build status
[00:03:09.637] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:09.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:84:0) after 1m0s
[00:04:09.727] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:84:0)
[00:04:09.727] Timer tick, asking Buildbucket for the build status
[00:04:09.783] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:09.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:85:0) after 1m0s
[00:05:09.806] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:85:0)
[00:05:09.806] Timer tick, asking Buildbucket for the build status
[00:05:10.569] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:10.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:86:0) after 1m0s
[00:06:10.651] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:86:0)
[00:06:10.651] Timer tick, asking Buildbucket for the build status
[00:06:10.732] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:10.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:87:0) after 1m0s
[00:07:10.755] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:87:0)
[00:07:10.755] Timer tick, asking Buildbucket for the build status
[00:07:10.835] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:10.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:88:0) after 1m0s
[00:08:10.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:88:0)
[00:08:10.857] Timer tick, asking Buildbucket for the build status
[00:08:11.348] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:11.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:89:0) after 1m0s
[00:09:11.398] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:89:0)
[00:09:11.398] Timer tick, asking Buildbucket for the build status
[00:09:11.481] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:11.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:90:0) after 1m0s
[00:10:11.582] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:90:0)
[00:10:11.582] Timer tick, asking Buildbucket for the build status
[00:10:11.675] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:11.675] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:91:0) after 1m0s
[00:11:11.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:91:0)
[00:11:11.701] Timer tick, asking Buildbucket for the build status
[00:11:11.838] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:11.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:92:0) after 1m0s
[00:12:11.837] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:92:0)
[00:12:11.837] Timer tick, asking Buildbucket for the build status
[00:12:11.946] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:11.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:93:0) after 1m0s
[00:13:11.969] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:93:0)
[00:13:11.969] Timer tick, asking Buildbucket for the build status
[00:13:12.359] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:12.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:94:0) after 1m0s
[00:14:12.449] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:94:0)
[00:14:12.449] Timer tick, asking Buildbucket for the build status
[00:14:12.687] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:12.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:95:0) after 1m0s
[00:15:12.836] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:95:0)
[00:15:12.836] Timer tick, asking Buildbucket for the build status
[00:15:13.319] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:13.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:96:0) after 1m0s
[00:16:13.376] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:96:0)
[00:16:13.376] Timer tick, asking Buildbucket for the build status
[00:16:13.816] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:13.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:97:0) after 1m0s
[00:17:13.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:97:0)
[00:17:13.910] Timer tick, asking Buildbucket for the build status
[00:17:14.310] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:14.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:98:0) after 1m0s
[00:18:14.346] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:98:0)
[00:18:14.346] Timer tick, asking Buildbucket for the build status
[00:18:14.465] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:14.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:99:0) after 1m0s
[00:19:14.679] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:99:0)
[00:19:14.679] Timer tick, asking Buildbucket for the build status
[00:19:14.767] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:14.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:100:0) after 1m0s
[00:20:14.869] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:100:0)
[00:20:14.869] Timer tick, asking Buildbucket for the build status
[00:20:14.930] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:14.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:101:0) after 1m0s
[00:21:15.022] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:101:0)
[00:21:15.022] Timer tick, asking Buildbucket for the build status
[00:21:15.118] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:15.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:102:0) after 1m0s
[00:22:15.149] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:102:0)
[00:22:15.149] Timer tick, asking Buildbucket for the build status
[00:22:16.228] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:16.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:103:0) after 1m0s
[00:23:16.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:103:0)
[00:23:16.306] Timer tick, asking Buildbucket for the build status
[00:23:16.366] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:16.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:104:0) after 1m0s
[00:24:16.459] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:104:0)
[00:24:16.459] Timer tick, asking Buildbucket for the build status
[00:24:16.866] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:16.866] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:105:0) after 1m0s
[00:25:16.889] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:105:0)
[00:25:16.889] Timer tick, asking Buildbucket for the build status
[00:25:17.315] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:17.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:106:0) after 1m0s
[00:26:17.334] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:106:0)
[00:26:17.334] Timer tick, asking Buildbucket for the build status
[00:26:17.449] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:17.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:107:0) after 1m0s
[00:27:17.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:107:0)
[00:27:17.475] Timer tick, asking Buildbucket for the build status
[00:27:17.646] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:17.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:108:0) after 1m0s
[00:28:17.668] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:108:0)
[00:28:17.668] Timer tick, asking Buildbucket for the build status
[00:28:17.766] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:17.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:109:0) after 1m0s
[00:29:17.830] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:109:0)
[00:29:17.830] Timer tick, asking Buildbucket for the build status
[00:29:17.944] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:17.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:110:0) after 1m0s
[00:30:17.967] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:110:0)
[00:30:17.967] Timer tick, asking Buildbucket for the build status
[00:30:18.327] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:18.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:111:0) after 1m0s
[00:31:18.415] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:111:0)
[00:31:18.415] Timer tick, asking Buildbucket for the build status
[00:31:18.754] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:18.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:112:0) after 1m0s
[00:32:18.781] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:112:0)
[00:32:18.782] Timer tick, asking Buildbucket for the build status
[00:32:18.827] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:18.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:113:0) after 1m0s
[00:33:18.875] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:113:0)
[00:33:18.875] Timer tick, asking Buildbucket for the build status
[00:33:18.985] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:18.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:114:0) after 1m0s
[00:34:19.010] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:114:0)
[00:34:19.010] Timer tick, asking Buildbucket for the build status
[00:34:19.219] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:19.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:115:0) after 1m0s
[00:35:19.354] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:115:0)
[00:35:19.354] Timer tick, asking Buildbucket for the build status
[00:35:19.886] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:19.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:116:0) after 1m0s
[00:36:19.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:116:0)
[00:36:19.907] Timer tick, asking Buildbucket for the build status
[00:36:20.170] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:20.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:117:0) after 1m0s
[00:37:20.244] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:117:0)
[00:37:20.244] Timer tick, asking Buildbucket for the build status
[00:37:20.335] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:20.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:118:0) after 1m0s
[00:38:20.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:118:0)
[00:38:20.355] Timer tick, asking Buildbucket for the build status
[00:38:20.811] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:20.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:119:0) after 1m0s
[00:39:20.833] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:119:0)
[00:39:20.833] Timer tick, asking Buildbucket for the build status
[00:39:20.924] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:20.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:120:0) after 1m0s
[00:40:20.946] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:120:0)
[00:40:20.946] Timer tick, asking Buildbucket for the build status
[00:40:21.179] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:21.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:121:0) after 1m0s
[00:41:21.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:121:0)
[00:41:21.218] Timer tick, asking Buildbucket for the build status
[00:41:21.700] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:21.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:122:0) after 1m0s
[00:42:21.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:122:0)
[00:42:21.783] Timer tick, asking Buildbucket for the build status
[00:42:21.873] Build 8912357579342718368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:21.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9077787641521467696:123:0) after 1m0s
[00:42:29.312] Received PubSub notification, asking Buildbucket for the build status
[00:42:29.575] Build 8912357579342718368: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[00:42:29.575] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558917748907784",
"created_by": "project:fuchsia",
"created_ts": "1558910500160348",
"failure_reason": "INFRA_FAILURE",
"id": "8912357579342718368",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"e698958ad8031e0f17202e06f5de53989852bb66\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"recipe infra failure: Infra Failure: Step('buildbucket.run.collect') (timeout)\"}, \"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-1-jg6t\", \"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\": \"e698958ad8031e0f17202e06f5de53989852bb66\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-1-jg6t\"], \"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\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"recipe infra failure: Infra Failure: Step('buildbucket.run.collect') (timeout)\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1558910508781884",
"status": "COMPLETED",
"status_changed_ts": "1558910509574011",
"tags": [
"builder:clang",
"buildset:commit/git/e698958ad8031e0f17202e06f5de53989852bb66",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/e698958ad8031e0f17202e06f5de53989852bb66",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077787641521467696",
"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/8912357579342718368/+/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:450f200a40f36710",
"user_agent:luci-scheduler"
],
"updated_ts": "1558917748966026",
"url": "https://ci.chromium.org/b/8912357579342718368",
"utcnow_ts": "1558917749564589"
}
}
[00:42:29.575] Invocation finished in 2h0m50.877476367s with status FAILED