[01:03:50.255] New invocation is queued and will start shortly
[01:03:51.309] Starting the invocation (attempt 1)
[01:03:51.380] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:03:51.380] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:03:51.380] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078775262186626736",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"3acc1d1be329d9f6e363c34c455fec86e0c4935c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078775262186626736",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/3acc1d1be329d9f6e363c34c455fec86e0c4935c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3acc1d1be329d9f6e363c34c455fec86e0c4935c",
"gitiles_ref:refs/heads/master"
]
}
[01:03:52.173] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1557968631552328",
"id": "8913345200160478592",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"3acc1d1be329d9f6e363c34c455fec86e0c4935c\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557968631912230",
"tags": [
"builder:clang",
"buildset:commit/git/3acc1d1be329d9f6e363c34c455fec86e0c4935c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3acc1d1be329d9f6e363c34c455fec86e0c4935c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078775262186626736",
"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/8913345200160478592/+/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": "1557968631912362",
"url": "https://ci.chromium.org/b/8913345200160478592",
"utcnow_ts": "1557968632150658"
}
}
[01:03:52.173] Task URL: https://ci.chromium.org/b/8913345200160478592
[01:03:52.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:2:0) after 1m0s
[01:04:07.391] Received PubSub notification, asking Buildbucket for the build status
[01:04:07.649] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:52.185] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:2:0)
[01:04:52.185] Timer tick, asking Buildbucket for the build status
[01:04:52.625] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:52.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:4:0) after 1m0s
[01:05:52.644] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:4:0)
[01:05:52.644] Timer tick, asking Buildbucket for the build status
[01:05:52.786] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:52.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:5:0) after 1m0s
[01:06:52.813] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:5:0)
[01:06:52.813] Timer tick, asking Buildbucket for the build status
[01:06:52.928] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:52.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:6:0) after 1m0s
[01:07:52.917] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:6:0)
[01:07:52.917] Timer tick, asking Buildbucket for the build status
[01:07:52.989] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:52.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:7:0) after 1m0s
[01:08:52.976] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:7:0)
[01:08:52.976] Timer tick, asking Buildbucket for the build status
[01:08:53.109] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:53.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:8:0) after 1m0s
[01:09:53.589] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:8:0)
[01:09:53.589] Timer tick, asking Buildbucket for the build status
[01:09:53.743] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:53.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:9:0) after 1m0s
[01:10:53.775] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:9:0)
[01:10:53.775] Timer tick, asking Buildbucket for the build status
[01:10:54.336] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:54.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:10:0) after 1m0s
[01:11:54.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:10:0)
[01:11:54.362] Timer tick, asking Buildbucket for the build status
[01:11:54.473] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:54.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:11:0) after 1m0s
[01:12:54.576] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:11:0)
[01:12:54.576] Timer tick, asking Buildbucket for the build status
[01:12:54.760] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:54.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:12:0) after 1m0s
[01:13:54.915] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:12:0)
[01:13:54.915] Timer tick, asking Buildbucket for the build status
[01:13:54.972] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:54.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:13:0) after 1m0s
[01:14:55.020] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:13:0)
[01:14:55.020] Timer tick, asking Buildbucket for the build status
[01:14:55.158] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:55.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:14:0) after 1m0s
[01:15:55.406] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:14:0)
[01:15:55.406] Timer tick, asking Buildbucket for the build status
[01:15:55.576] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:55.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:15:0) after 1m0s
[01:16:55.578] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:15:0)
[01:16:55.578] Timer tick, asking Buildbucket for the build status
[01:16:55.869] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:55.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:16:0) after 1m0s
[01:17:55.962] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:16:0)
[01:17:55.962] Timer tick, asking Buildbucket for the build status
[01:17:56.060] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:56.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:17:0) after 1m0s
[01:18:56.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:17:0)
[01:18:56.084] Timer tick, asking Buildbucket for the build status
[01:18:56.239] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:56.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:18:0) after 1m0s
[01:19:56.565] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:18:0)
[01:19:56.565] Timer tick, asking Buildbucket for the build status
[01:19:56.673] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:56.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:19:0) after 1m0s
[01:20:56.685] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:19:0)
[01:20:56.685] Timer tick, asking Buildbucket for the build status
[01:20:56.776] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:56.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:20:0) after 1m0s
[01:21:56.894] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:20:0)
[01:21:56.894] Timer tick, asking Buildbucket for the build status
[01:21:56.979] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:56.979] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:21:0) after 1m0s
[01:22:57.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:21:0)
[01:22:57.207] Timer tick, asking Buildbucket for the build status
[01:22:57.392] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:57.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:22:0) after 1m0s
[01:23:57.413] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:22:0)
[01:23:57.413] Timer tick, asking Buildbucket for the build status
[01:23:57.533] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:57.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:23:0) after 1m0s
[01:24:57.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:23:0)
[01:24:57.553] Timer tick, asking Buildbucket for the build status
[01:24:57.610] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:57.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:24:0) after 1m0s
[01:25:57.642] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:24:0)
[01:25:57.642] Timer tick, asking Buildbucket for the build status
[01:25:57.731] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:57.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:25:0) after 1m0s
[01:26:57.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:25:0)
[01:26:57.768] Timer tick, asking Buildbucket for the build status
[01:26:57.944] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:57.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:26:0) after 1m0s
[01:27:57.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:26:0)
[01:27:57.968] Timer tick, asking Buildbucket for the build status
[01:27:58.192] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:58.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:27:0) after 1m0s
[01:28:58.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:27:0)
[01:28:58.245] Timer tick, asking Buildbucket for the build status
[01:28:58.339] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:58.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:28:0) after 1m0s
[01:29:58.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:28:0)
[01:29:58.351] Timer tick, asking Buildbucket for the build status
[01:29:58.462] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:58.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:29:0) after 1m0s
[01:30:58.506] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:29:0)
[01:30:58.506] Timer tick, asking Buildbucket for the build status
[01:30:58.559] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:58.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:30:0) after 1m0s
[01:31:58.585] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:30:0)
[01:31:58.585] Timer tick, asking Buildbucket for the build status
[01:31:58.749] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:58.749] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:31:0) after 1m0s
[01:32:58.770] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:31:0)
[01:32:58.770] Timer tick, asking Buildbucket for the build status
[01:32:58.843] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:58.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:32:0) after 1m0s
[01:33:58.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:32:0)
[01:33:58.922] Timer tick, asking Buildbucket for the build status
[01:33:59.004] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:59.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:33:0) after 1m0s
[01:34:59.005] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:33:0)
[01:34:59.005] Timer tick, asking Buildbucket for the build status
[01:34:59.287] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:59.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:34:0) after 1m0s
[01:35:59.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:34:0)
[01:35:59.286] Timer tick, asking Buildbucket for the build status
[01:35:59.442] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:59.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:35:0) after 1m0s
[01:36:59.488] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:35:0)
[01:36:59.488] Timer tick, asking Buildbucket for the build status
[01:36:59.616] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:59.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:36:0) after 1m0s
[01:37:59.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:36:0)
[01:37:59.618] Timer tick, asking Buildbucket for the build status
[01:37:59.753] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:59.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:37:0) after 1m0s
[01:38:59.880] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:37:0)
[01:38:59.880] Timer tick, asking Buildbucket for the build status
[01:39:00.440] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:00.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:38:0) after 1m0s
[01:40:00.460] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:38:0)
[01:40:00.460] Timer tick, asking Buildbucket for the build status
[01:40:00.545] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:00.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:39:0) after 1m0s
[01:41:00.564] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:39:0)
[01:41:00.564] Timer tick, asking Buildbucket for the build status
[01:41:00.664] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:00.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:40:0) after 1m0s
[01:42:00.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:40:0)
[01:42:00.689] Timer tick, asking Buildbucket for the build status
[01:42:01.125] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:01.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:41:0) after 1m0s
[01:43:01.161] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:41:0)
[01:43:01.161] Timer tick, asking Buildbucket for the build status
[01:43:01.301] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:01.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:42:0) after 1m0s
[01:44:01.275] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:42:0)
[01:44:01.275] Timer tick, asking Buildbucket for the build status
[01:44:01.410] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:01.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:43:0) after 1m0s
[01:45:01.420] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:43:0)
[01:45:01.420] Timer tick, asking Buildbucket for the build status
[01:45:01.505] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:01.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:44:0) after 1m0s
[01:46:01.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:44:0)
[01:46:01.530] Timer tick, asking Buildbucket for the build status
[01:46:01.607] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:01.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:45:0) after 1m0s
[01:47:01.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:45:0)
[01:47:01.606] Timer tick, asking Buildbucket for the build status
[01:47:01.698] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:01.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:46:0) after 1m0s
[01:48:01.909] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:46:0)
[01:48:01.909] Timer tick, asking Buildbucket for the build status
[01:48:01.956] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:01.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:47:0) after 1m0s
[01:49:02.062] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:47:0)
[01:49:02.062] Timer tick, asking Buildbucket for the build status
[01:49:02.101] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:02.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:48:0) after 1m0s
[01:50:02.131] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:48:0)
[01:50:02.131] Timer tick, asking Buildbucket for the build status
[01:50:02.521] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:02.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:49:0) after 1m0s
[01:51:02.571] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:49:0)
[01:51:02.571] Timer tick, asking Buildbucket for the build status
[01:51:02.646] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:02.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:50:0) after 1m0s
[01:52:02.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:50:0)
[01:52:02.684] Timer tick, asking Buildbucket for the build status
[01:52:02.840] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:02.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:51:0) after 1m0s
[01:53:02.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:51:0)
[01:53:02.838] Timer tick, asking Buildbucket for the build status
[01:53:02.903] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:02.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:52:0) after 1m0s
[01:54:02.931] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:52:0)
[01:54:02.931] Timer tick, asking Buildbucket for the build status
[01:54:02.992] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:02.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:53:0) after 1m0s
[01:55:03.019] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:53:0)
[01:55:03.019] Timer tick, asking Buildbucket for the build status
[01:55:03.164] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:03.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:54:0) after 1m0s
[01:56:03.178] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:54:0)
[01:56:03.178] Timer tick, asking Buildbucket for the build status
[01:56:03.389] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:03.389] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:55:0) after 1m0s
[01:57:03.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:55:0)
[01:57:03.493] Timer tick, asking Buildbucket for the build status
[01:57:03.578] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:03.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:56:0) after 1m0s
[01:58:03.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:56:0)
[01:58:03.698] Timer tick, asking Buildbucket for the build status
[01:58:03.868] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:03.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:57:0) after 1m0s
[01:59:03.911] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:57:0)
[01:59:03.911] Timer tick, asking Buildbucket for the build status
[01:59:04.081] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:04.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:58:0) after 1m0s
[02:00:04.106] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:58:0)
[02:00:04.106] Timer tick, asking Buildbucket for the build status
[02:00:04.284] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:04.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:59:0) after 1m0s
[02:01:04.320] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:59:0)
[02:01:04.320] Timer tick, asking Buildbucket for the build status
[02:01:04.651] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:04.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:60:0) after 1m0s
[02:02:04.696] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:60:0)
[02:02:04.696] Timer tick, asking Buildbucket for the build status
[02:02:04.887] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:04.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:61:0) after 1m0s
[02:03:04.907] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:61:0)
[02:03:04.907] Timer tick, asking Buildbucket for the build status
[02:03:05.126] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:05.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:62:0) after 1m0s
[02:04:05.207] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:62:0)
[02:04:05.207] Timer tick, asking Buildbucket for the build status
[02:04:05.280] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:05.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:63:0) after 1m0s
[02:05:05.301] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:63:0)
[02:05:05.301] Timer tick, asking Buildbucket for the build status
[02:05:05.464] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:05.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:64:0) after 1m0s
[02:06:05.461] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:64:0)
[02:06:05.461] Timer tick, asking Buildbucket for the build status
[02:06:05.732] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:05.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:65:0) after 1m0s
[02:07:05.854] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:65:0)
[02:07:05.854] Timer tick, asking Buildbucket for the build status
[02:07:06.526] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:06.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:66:0) after 1m0s
[02:08:06.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:66:0)
[02:08:06.542] Timer tick, asking Buildbucket for the build status
[02:08:06.688] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:06.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:67:0) after 1m0s
[02:09:06.729] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:67:0)
[02:09:06.729] Timer tick, asking Buildbucket for the build status
[02:09:06.860] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:06.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:68:0) after 1m0s
[02:10:06.922] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:68:0)
[02:10:06.922] Timer tick, asking Buildbucket for the build status
[02:10:06.980] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:06.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:69:0) after 1m0s
[02:11:07.115] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:69:0)
[02:11:07.115] Timer tick, asking Buildbucket for the build status
[02:11:07.366] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:07.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:70:0) after 1m0s
[02:12:07.394] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:70:0)
[02:12:07.394] Timer tick, asking Buildbucket for the build status
[02:12:07.463] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:07.463] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:71:0) after 1m0s
[02:13:07.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:71:0)
[02:13:07.471] Timer tick, asking Buildbucket for the build status
[02:13:07.525] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:07.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:72:0) after 1m0s
[02:14:07.569] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:72:0)
[02:14:07.569] Timer tick, asking Buildbucket for the build status
[02:14:07.999] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:07.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:73:0) after 1m0s
[02:15:08.461] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:73:0)
[02:15:08.461] Timer tick, asking Buildbucket for the build status
[02:15:08.607] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:08.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:74:0) after 1m0s
[02:16:08.598] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:74:0)
[02:16:08.598] Timer tick, asking Buildbucket for the build status
[02:16:13.166] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:13.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:75:0) after 1m0s
[02:17:13.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:75:0)
[02:17:13.209] Timer tick, asking Buildbucket for the build status
[02:17:13.354] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:13.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:76:0) after 1m0s
[02:18:13.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:76:0)
[02:18:13.377] Timer tick, asking Buildbucket for the build status
[02:18:13.441] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:13.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:77:0) after 1m0s
[02:19:13.479] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:77:0)
[02:19:13.479] Timer tick, asking Buildbucket for the build status
[02:19:13.521] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:13.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:78:0) after 1m0s
[02:20:13.555] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:78:0)
[02:20:13.555] Timer tick, asking Buildbucket for the build status
[02:20:13.593] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:13.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:79:0) after 1m0s
[02:21:13.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:79:0)
[02:21:13.616] Timer tick, asking Buildbucket for the build status
[02:21:13.730] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:13.730] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:80:0) after 1m0s
[02:22:13.803] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:80:0)
[02:22:13.803] Timer tick, asking Buildbucket for the build status
[02:22:13.922] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:13.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:81:0) after 1m0s
[02:23:14.022] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:81:0)
[02:23:14.022] Timer tick, asking Buildbucket for the build status
[02:23:14.588] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:14.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:82:0) after 1m0s
[02:24:14.638] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:82:0)
[02:24:14.638] Timer tick, asking Buildbucket for the build status
[02:24:14.753] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:14.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:83:0) after 1m0s
[02:25:14.779] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:83:0)
[02:25:14.779] Timer tick, asking Buildbucket for the build status
[02:25:15.340] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:15.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:84:0) after 1m0s
[02:26:15.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:84:0)
[02:26:15.399] Timer tick, asking Buildbucket for the build status
[02:26:15.564] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:15.564] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:85:0) after 1m0s
[02:27:15.594] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:85:0)
[02:27:15.594] Timer tick, asking Buildbucket for the build status
[02:27:15.789] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:15.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:86:0) after 1m0s
[02:28:15.947] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:86:0)
[02:28:15.947] Timer tick, asking Buildbucket for the build status
[02:28:16.065] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:16.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:87:0) after 1m0s
[02:29:16.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:87:0)
[02:29:16.088] Timer tick, asking Buildbucket for the build status
[02:29:16.142] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:16.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:88:0) after 1m0s
[02:30:16.157] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:88:0)
[02:30:16.157] Timer tick, asking Buildbucket for the build status
[02:30:16.261] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:16.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:89:0) after 1m0s
[02:31:16.283] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:89:0)
[02:31:16.284] Timer tick, asking Buildbucket for the build status
[02:31:16.355] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:16.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:90:0) after 1m0s
[02:32:16.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:90:0)
[02:32:16.379] Timer tick, asking Buildbucket for the build status
[02:32:16.486] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:16.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:91:0) after 1m0s
[02:33:16.511] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:91:0)
[02:33:16.511] Timer tick, asking Buildbucket for the build status
[02:33:16.556] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:16.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:92:0) after 1m0s
[02:34:16.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:92:0)
[02:34:16.676] Timer tick, asking Buildbucket for the build status
[02:34:16.975] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:16.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:93:0) after 1m0s
[02:35:17.105] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:93:0)
[02:35:17.105] Timer tick, asking Buildbucket for the build status
[02:35:17.152] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:17.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:94:0) after 1m0s
[02:36:17.200] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:94:0)
[02:36:17.201] Timer tick, asking Buildbucket for the build status
[02:36:17.364] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:17.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:95:0) after 1m0s
[02:37:17.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:95:0)
[02:37:17.390] Timer tick, asking Buildbucket for the build status
[02:37:17.430] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:17.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:96:0) after 1m0s
[02:38:17.471] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:96:0)
[02:38:17.471] Timer tick, asking Buildbucket for the build status
[02:38:17.543] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:17.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:97:0) after 1m0s
[02:39:17.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:97:0)
[02:39:17.912] Timer tick, asking Buildbucket for the build status
[02:39:18.407] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:18.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:98:0) after 1m0s
[02:40:18.390] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:98:0)
[02:40:18.390] Timer tick, asking Buildbucket for the build status
[02:40:18.650] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:18.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:99:0) after 1m0s
[02:41:18.673] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:99:0)
[02:41:18.673] Timer tick, asking Buildbucket for the build status
[02:41:19.091] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:19.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:100:0) after 1m0s
[02:42:19.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:100:0)
[02:42:19.114] Timer tick, asking Buildbucket for the build status
[02:42:19.170] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:19.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:101:0) after 1m0s
[02:43:19.265] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:101:0)
[02:43:19.265] Timer tick, asking Buildbucket for the build status
[02:43:19.344] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:19.344] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:102:0) after 1m0s
[02:44:19.382] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:102:0)
[02:44:19.382] Timer tick, asking Buildbucket for the build status
[02:44:19.484] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:19.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:103:0) after 1m0s
[02:45:19.527] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:103:0)
[02:45:19.527] Timer tick, asking Buildbucket for the build status
[02:45:19.668] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:19.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:104:0) after 1m0s
[02:46:19.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:104:0)
[02:46:19.792] Timer tick, asking Buildbucket for the build status
[02:46:19.847] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:19.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:105:0) after 1m0s
[02:47:19.872] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:105:0)
[02:47:19.872] Timer tick, asking Buildbucket for the build status
[02:47:20.216] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:20.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:106:0) after 1m0s
[02:48:20.245] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:106:0)
[02:48:20.245] Timer tick, asking Buildbucket for the build status
[02:48:20.456] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:20.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:107:0) after 1m0s
[02:49:20.487] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:107:0)
[02:49:20.487] Timer tick, asking Buildbucket for the build status
[02:49:20.566] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:20.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:108:0) after 1m0s
[02:50:20.618] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:108:0)
[02:50:20.618] Timer tick, asking Buildbucket for the build status
[02:50:20.786] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:20.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:109:0) after 1m0s
[02:51:20.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:109:0)
[02:51:20.792] Timer tick, asking Buildbucket for the build status
[02:51:20.951] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:20.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:110:0) after 1m0s
[02:52:21.012] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:110:0)
[02:52:21.012] Timer tick, asking Buildbucket for the build status
[02:52:21.085] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:21.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:111:0) after 1m0s
[02:53:21.111] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:111:0)
[02:53:21.111] Timer tick, asking Buildbucket for the build status
[02:53:21.229] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:21.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:112:0) after 1m0s
[02:54:21.252] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:112:0)
[02:54:21.252] Timer tick, asking Buildbucket for the build status
[02:54:21.311] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:21.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:113:0) after 1m0s
[02:55:21.334] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:113:0)
[02:55:21.334] Timer tick, asking Buildbucket for the build status
[02:55:21.452] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:21.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:114:0) after 1m0s
[02:56:21.480] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:114:0)
[02:56:21.480] Timer tick, asking Buildbucket for the build status
[02:56:21.535] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:21.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:115:0) after 1m0s
[02:57:21.557] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:115:0)
[02:57:21.557] Timer tick, asking Buildbucket for the build status
[02:57:21.873] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:21.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:116:0) after 1m0s
[02:58:21.896] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:116:0)
[02:58:21.896] Timer tick, asking Buildbucket for the build status
[02:58:21.963] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:21.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:117:0) after 1m0s
[02:59:22.029] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:117:0)
[02:59:22.029] Timer tick, asking Buildbucket for the build status
[02:59:22.539] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:22.539] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:118:0) after 1m0s
[03:00:22.657] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:118:0)
[03:00:22.657] Timer tick, asking Buildbucket for the build status
[03:00:22.771] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:22.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:119:0) after 1m0s
[03:01:22.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:119:0)
[03:01:22.796] Timer tick, asking Buildbucket for the build status
[03:01:23.118] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:23.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:120:0) after 1m0s
[03:02:23.426] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:120:0)
[03:02:23.426] Timer tick, asking Buildbucket for the build status
[03:02:23.498] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:23.498] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:121:0) after 1m0s
[03:03:24.458] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:121:0)
[03:03:24.458] Timer tick, asking Buildbucket for the build status
[03:03:24.691] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:24.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:122:0) after 1m0s
[03:04:24.734] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:122:0)
[03:04:24.734] Timer tick, asking Buildbucket for the build status
[03:04:24.897] Build 8913345200160478592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:24.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078775262186626736:123:0) after 1m0s
[03:04:26.459] Received PubSub notification, asking Buildbucket for the build status
[03:04:26.729] Build 8913345200160478592: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:04:26.729] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1557975865845488",
"created_by": "project:fuchsia",
"created_ts": "1557968631552328",
"failure_reason": "BUILD_FAILURE",
"id": "8913345200160478592",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"3acc1d1be329d9f6e363c34c455fec86e0c4935c\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-c-0-36f5\", \"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\": \"3acc1d1be329d9f6e363c34c455fec86e0c4935c\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-0-36f5\"], \"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\": [\"4300-ce42b68\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1557968646106877",
"status": "COMPLETED",
"status_changed_ts": "1557968646660038",
"tags": [
"builder:clang",
"buildset:commit/git/3acc1d1be329d9f6e363c34c455fec86e0c4935c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/3acc1d1be329d9f6e363c34c455fec86e0c4935c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078775262186626736",
"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/8913345200160478592/+/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:44d6fc42cc8b5910",
"user_agent:luci-scheduler"
],
"updated_ts": "1557975866039591",
"url": "https://ci.chromium.org/b/8913345200160478592",
"utcnow_ts": "1557975866719685"
}
}
[03:04:26.729] Invocation finished in 2h0m36.493569338s with status FAILED