[00:40:42.762] New invocation is queued and will start shortly
[00:40:43.856] Starting the invocation (attempt 1)
[00:40:43.919] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:40:43.919] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:40:43.919] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078233135287794672",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"bceadcbb0ca8e81bf3cec11c74d877461018c9f2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078233135287794672",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/bceadcbb0ca8e81bf3cec11c74d877461018c9f2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/bceadcbb0ca8e81bf3cec11c74d877461018c9f2",
"gitiles_ref:refs/heads/master"
]
}
[00:40:45.292] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558485644073690",
"id": "8912803073239027696",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"bceadcbb0ca8e81bf3cec11c74d877461018c9f2\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558485644942339",
"tags": [
"builder:clang",
"buildset:commit/git/bceadcbb0ca8e81bf3cec11c74d877461018c9f2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/bceadcbb0ca8e81bf3cec11c74d877461018c9f2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078233135287794672",
"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/8912803073239027696/+/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": "1558485644942481",
"url": "https://ci.chromium.org/b/8912803073239027696",
"utcnow_ts": "1558485645280297"
}
}
[00:40:45.292] Task URL: https://ci.chromium.org/b/8912803073239027696
[00:40:45.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:2:0) after 1m0s
[00:40:53.494] Received PubSub notification, asking Buildbucket for the build status
[00:40:53.790] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:45.377] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:2:0)
[00:41:45.377] Timer tick, asking Buildbucket for the build status
[00:41:45.868] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:45.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:4:0) after 1m0s
[00:42:46.380] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:4:0)
[00:42:46.380] Timer tick, asking Buildbucket for the build status
[00:42:46.426] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:46.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:5:0) after 1m0s
[00:43:46.475] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:5:0)
[00:43:46.475] Timer tick, asking Buildbucket for the build status
[00:43:47.364] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:47.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:6:0) after 1m0s
[00:44:47.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:6:0)
[00:44:47.399] Timer tick, asking Buildbucket for the build status
[00:44:47.458] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:47.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:7:0) after 1m0s
[00:45:47.481] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:7:0)
[00:45:47.481] Timer tick, asking Buildbucket for the build status
[00:45:47.799] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:47.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:8:0) after 1m0s
[00:46:47.835] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:8:0)
[00:46:47.835] Timer tick, asking Buildbucket for the build status
[00:46:47.881] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:47.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:9:0) after 1m0s
[00:47:47.861] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:9:0)
[00:47:47.862] Timer tick, asking Buildbucket for the build status
[00:47:48.082] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:48.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:10:0) after 1m0s
[00:48:48.140] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:10:0)
[00:48:48.140] Timer tick, asking Buildbucket for the build status
[00:48:48.392] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:48.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:11:0) after 1m0s
[00:49:49.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:11:0)
[00:49:49.393] Timer tick, asking Buildbucket for the build status
[00:49:49.515] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:49.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:12:0) after 1m0s
[00:50:49.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:12:0)
[00:50:49.796] Timer tick, asking Buildbucket for the build status
[00:50:50.175] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:50.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:13:0) after 1m0s
[00:51:50.286] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:13:0)
[00:51:50.286] Timer tick, asking Buildbucket for the build status
[00:51:50.376] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:50.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:14:0) after 1m0s
[00:52:50.387] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:14:0)
[00:52:50.387] Timer tick, asking Buildbucket for the build status
[00:52:50.560] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:50.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:15:0) after 1m0s
[00:53:50.745] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:15:0)
[00:53:50.745] Timer tick, asking Buildbucket for the build status
[00:53:50.791] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:50.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:16:0) after 1m0s
[00:54:51.309] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:16:0)
[00:54:51.309] Timer tick, asking Buildbucket for the build status
[00:54:51.355] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:51.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:17:0) after 1m0s
[00:55:51.386] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:17:0)
[00:55:51.386] Timer tick, asking Buildbucket for the build status
[00:55:51.486] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:51.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:18:0) after 1m0s
[00:56:51.503] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:18:0)
[00:56:51.503] Timer tick, asking Buildbucket for the build status
[00:56:51.662] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:51.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:19:0) after 1m0s
[00:57:51.703] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:19:0)
[00:57:51.704] Timer tick, asking Buildbucket for the build status
[00:57:52.163] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:52.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:20:0) after 1m0s
[00:59:04.872] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:20:0)
[00:59:04.872] Timer tick, asking Buildbucket for the build status
[00:59:05.435] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:05.435] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:21:0) after 1m0s
[01:00:05.451] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:21:0)
[01:00:05.451] Timer tick, asking Buildbucket for the build status
[01:00:05.607] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:05.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:22:0) after 1m0s
[01:01:05.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:22:0)
[01:01:05.828] Timer tick, asking Buildbucket for the build status
[01:01:05.953] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:05.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:23:0) after 1m0s
[01:02:06.077] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:23:0)
[01:02:06.077] Timer tick, asking Buildbucket for the build status
[01:02:06.482] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:06.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:24:0) after 1m0s
[01:03:06.542] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:24:0)
[01:03:06.542] Timer tick, asking Buildbucket for the build status
[01:03:06.619] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:06.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:25:0) after 1m0s
[01:04:06.810] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:25:0)
[01:04:06.810] Timer tick, asking Buildbucket for the build status
[01:04:06.971] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:06.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:26:0) after 1m0s
[01:05:06.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:26:0)
[01:05:06.998] Timer tick, asking Buildbucket for the build status
[01:05:07.813] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:07.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:27:0) after 1m0s
[01:06:07.792] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:27:0)
[01:06:07.792] Timer tick, asking Buildbucket for the build status
[01:06:07.864] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:07.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:28:0) after 1m0s
[01:07:07.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:28:0)
[01:07:07.898] Timer tick, asking Buildbucket for the build status
[01:07:07.981] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:07.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:29:0) after 1m0s
[01:08:08.015] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:29:0)
[01:08:08.015] Timer tick, asking Buildbucket for the build status
[01:08:08.660] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:08.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:30:0) after 1m0s
[01:09:08.664] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:30:0)
[01:09:08.664] Timer tick, asking Buildbucket for the build status
[01:09:08.811] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:08.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:31:0) after 1m0s
[01:10:08.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:31:0)
[01:10:08.804] Timer tick, asking Buildbucket for the build status
[01:10:11.074] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:11.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:32:0) after 1m0s
[01:11:11.214] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:32:0)
[01:11:11.214] Timer tick, asking Buildbucket for the build status
[01:11:11.254] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:11.254] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:33:0) after 1m0s
[01:12:11.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:33:0)
[01:12:11.251] Timer tick, asking Buildbucket for the build status
[01:12:11.425] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:11.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:34:0) after 1m0s
[01:13:11.403] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:34:0)
[01:13:11.403] Timer tick, asking Buildbucket for the build status
[01:13:11.489] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:11.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:35:0) after 1m0s
[01:14:11.536] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:35:0)
[01:14:11.536] Timer tick, asking Buildbucket for the build status
[01:14:11.746] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:11.746] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:36:0) after 1m0s
[01:15:11.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:36:0)
[01:15:11.784] Timer tick, asking Buildbucket for the build status
[01:15:11.904] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:11.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:37:0) after 1m0s
[01:16:11.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:37:0)
[01:16:11.916] Timer tick, asking Buildbucket for the build status
[01:16:12.130] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:12.130] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:38:0) after 1m0s
[01:17:12.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:38:0)
[01:17:12.166] Timer tick, asking Buildbucket for the build status
[01:17:12.713] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:12.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:39:0) after 1m0s
[01:18:12.705] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:39:0)
[01:18:12.705] Timer tick, asking Buildbucket for the build status
[01:18:12.871] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:12.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:40:0) after 1m0s
[01:19:12.897] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:40:0)
[01:19:12.897] Timer tick, asking Buildbucket for the build status
[01:19:12.958] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:12.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:41:0) after 1m0s
[01:20:12.945] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:41:0)
[01:20:12.945] Timer tick, asking Buildbucket for the build status
[01:20:13.156] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:13.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:42:0) after 1m0s
[01:21:13.147] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:42:0)
[01:21:13.147] Timer tick, asking Buildbucket for the build status
[01:21:13.207] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:13.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:43:0) after 1m0s
[01:22:13.241] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:43:0)
[01:22:13.241] Timer tick, asking Buildbucket for the build status
[01:22:13.347] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:13.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:44:0) after 1m0s
[01:23:13.332] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:44:0)
[01:23:13.332] Timer tick, asking Buildbucket for the build status
[01:23:13.642] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:13.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:45:0) after 1m0s
[01:24:13.677] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:45:0)
[01:24:13.677] Timer tick, asking Buildbucket for the build status
[01:24:14.201] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:14.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:46:0) after 1m0s
[01:25:14.203] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:46:0)
[01:25:14.203] Timer tick, asking Buildbucket for the build status
[01:25:14.676] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:14.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:47:0) after 1m0s
[01:26:14.716] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:47:0)
[01:26:14.716] Timer tick, asking Buildbucket for the build status
[01:26:14.794] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:14.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:48:0) after 1m0s
[01:27:14.827] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:48:0)
[01:27:14.827] Timer tick, asking Buildbucket for the build status
[01:27:15.009] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:15.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:49:0) after 1m0s
[01:28:15.009] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:49:0)
[01:28:15.009] Timer tick, asking Buildbucket for the build status
[01:28:15.083] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:15.083] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:50:0) after 1m0s
[01:29:15.196] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:50:0)
[01:29:15.196] Timer tick, asking Buildbucket for the build status
[01:29:15.676] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:15.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:51:0) after 1m0s
[01:30:15.729] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:51:0)
[01:30:15.729] Timer tick, asking Buildbucket for the build status
[01:30:15.803] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:15.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:52:0) after 1m0s
[01:31:15.798] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:52:0)
[01:31:15.798] Timer tick, asking Buildbucket for the build status
[01:31:15.911] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:15.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:53:0) after 1m0s
[01:32:15.918] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:53:0)
[01:32:15.918] Timer tick, asking Buildbucket for the build status
[01:32:15.973] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:15.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:54:0) after 1m0s
[01:33:15.982] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:54:0)
[01:33:15.982] Timer tick, asking Buildbucket for the build status
[01:33:16.095] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:16.095] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:55:0) after 1m0s
[01:34:16.189] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:55:0)
[01:34:16.189] Timer tick, asking Buildbucket for the build status
[01:34:16.533] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:16.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:56:0) after 1m0s
[01:35:16.533] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:56:0)
[01:35:16.533] Timer tick, asking Buildbucket for the build status
[01:35:16.735] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:16.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:57:0) after 1m0s
[01:36:16.756] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:57:0)
[01:36:16.756] Timer tick, asking Buildbucket for the build status
[01:36:16.822] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:16.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:58:0) after 1m0s
[01:37:16.799] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:58:0)
[01:37:16.799] Timer tick, asking Buildbucket for the build status
[01:37:17.002] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:17.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:59:0) after 1m0s
[01:38:17.038] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:59:0)
[01:38:17.038] Timer tick, asking Buildbucket for the build status
[01:38:17.346] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:17.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:60:0) after 1m0s
[01:39:17.365] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:60:0)
[01:39:17.365] Timer tick, asking Buildbucket for the build status
[01:39:17.608] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:17.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:61:0) after 1m0s
[01:40:17.629] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:61:0)
[01:40:17.629] Timer tick, asking Buildbucket for the build status
[01:40:17.786] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:17.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:62:0) after 1m0s
[01:41:17.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:62:0)
[01:41:17.828] Timer tick, asking Buildbucket for the build status
[01:41:18.016] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:18.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:63:0) after 1m0s
[01:42:18.056] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:63:0)
[01:42:18.056] Timer tick, asking Buildbucket for the build status
[01:42:18.098] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:18.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:64:0) after 1m0s
[01:43:18.151] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:64:0)
[01:43:18.151] Timer tick, asking Buildbucket for the build status
[01:43:18.323] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:18.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:65:0) after 1m0s
[01:44:18.392] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:65:0)
[01:44:18.392] Timer tick, asking Buildbucket for the build status
[01:44:18.808] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:18.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:66:0) after 1m0s
[01:45:18.905] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:66:0)
[01:45:18.905] Timer tick, asking Buildbucket for the build status
[01:45:19.040] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:19.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:67:0) after 1m0s
[01:46:19.100] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:67:0)
[01:46:19.100] Timer tick, asking Buildbucket for the build status
[01:46:19.176] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:19.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:68:0) after 1m0s
[01:47:19.254] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:68:0)
[01:47:19.254] Timer tick, asking Buildbucket for the build status
[01:47:19.387] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:19.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:69:0) after 1m0s
[01:48:19.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:69:0)
[01:48:19.438] Timer tick, asking Buildbucket for the build status
[01:48:19.931] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:19.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:70:0) after 1m0s
[01:49:19.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:70:0)
[01:49:19.936] Timer tick, asking Buildbucket for the build status
[01:49:20.166] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:20.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:71:0) after 1m0s
[01:50:20.314] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:71:0)
[01:50:20.314] Timer tick, asking Buildbucket for the build status
[01:50:20.494] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:20.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:72:0) after 1m0s
[01:51:20.498] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:72:0)
[01:51:20.498] Timer tick, asking Buildbucket for the build status
[01:51:20.549] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:20.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:73:0) after 1m0s
[01:52:20.616] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:73:0)
[01:52:20.616] Timer tick, asking Buildbucket for the build status
[01:52:21.613] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:21.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:74:0) after 1m0s
[01:53:21.636] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:74:0)
[01:53:21.636] Timer tick, asking Buildbucket for the build status
[01:53:21.759] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:21.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:75:0) after 1m0s
[01:54:21.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:75:0)
[01:54:21.780] Timer tick, asking Buildbucket for the build status
[01:54:22.259] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:22.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:76:0) after 1m0s
[01:55:22.285] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:76:0)
[01:55:22.285] Timer tick, asking Buildbucket for the build status
[01:55:22.405] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:22.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:77:0) after 1m0s
[01:56:22.448] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:77:0)
[01:56:22.448] Timer tick, asking Buildbucket for the build status
[01:56:22.766] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:22.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:78:0) after 1m0s
[01:57:22.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:78:0)
[01:57:22.782] Timer tick, asking Buildbucket for the build status
[01:57:22.951] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:22.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:79:0) after 1m0s
[01:58:22.980] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:79:0)
[01:58:22.980] Timer tick, asking Buildbucket for the build status
[01:58:23.064] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:23.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:80:0) after 1m0s
[01:59:23.132] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:80:0)
[01:59:23.132] Timer tick, asking Buildbucket for the build status
[01:59:23.195] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:23.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:81:0) after 1m0s
[02:00:23.271] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:81:0)
[02:00:23.271] Timer tick, asking Buildbucket for the build status
[02:00:23.379] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:23.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:82:0) after 1m0s
[02:01:23.439] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:82:0)
[02:01:23.439] Timer tick, asking Buildbucket for the build status
[02:01:23.485] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:23.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:83:0) after 1m0s
[02:02:23.516] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:83:0)
[02:02:23.516] Timer tick, asking Buildbucket for the build status
[02:02:23.603] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:23.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:84:0) after 1m0s
[02:03:23.599] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:84:0)
[02:03:23.599] Timer tick, asking Buildbucket for the build status
[02:03:23.713] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:23.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:85:0) after 1m0s
[02:04:23.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:85:0)
[02:04:23.737] Timer tick, asking Buildbucket for the build status
[02:04:24.231] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:24.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:86:0) after 1m0s
[02:05:24.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:86:0)
[02:05:24.383] Timer tick, asking Buildbucket for the build status
[02:05:24.584] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:24.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:87:0) after 1m0s
[02:06:24.600] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:87:0)
[02:06:24.600] Timer tick, asking Buildbucket for the build status
[02:06:24.667] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:24.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:88:0) after 1m0s
[02:07:24.753] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:88:0)
[02:07:24.753] Timer tick, asking Buildbucket for the build status
[02:07:24.858] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:24.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:89:0) after 1m0s
[02:08:24.845] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:89:0)
[02:08:24.845] Timer tick, asking Buildbucket for the build status
[02:08:24.971] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:24.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:90:0) after 1m0s
[02:09:25.090] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:90:0)
[02:09:25.090] Timer tick, asking Buildbucket for the build status
[02:09:25.194] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:25.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:91:0) after 1m0s
[02:10:25.230] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:91:0)
[02:10:25.230] Timer tick, asking Buildbucket for the build status
[02:10:25.549] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:25.549] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:92:0) after 1m0s
[02:11:25.577] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:92:0)
[02:11:25.577] Timer tick, asking Buildbucket for the build status
[02:11:25.638] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:25.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:93:0) after 1m0s
[02:12:25.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:93:0)
[02:12:25.672] Timer tick, asking Buildbucket for the build status
[02:12:25.729] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:25.729] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:94:0) after 1m0s
[02:13:25.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:94:0)
[02:13:25.707] Timer tick, asking Buildbucket for the build status
[02:13:25.890] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:25.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:95:0) after 1m0s
[02:14:25.920] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:95:0)
[02:14:25.920] Timer tick, asking Buildbucket for the build status
[02:14:26.658] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:26.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:96:0) after 1m0s
[02:15:26.684] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:96:0)
[02:15:26.684] Timer tick, asking Buildbucket for the build status
[02:15:26.743] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:26.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:97:0) after 1m0s
[02:16:26.769] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:97:0)
[02:16:26.769] Timer tick, asking Buildbucket for the build status
[02:16:26.812] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:26.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:98:0) after 1m0s
[02:17:26.826] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:98:0)
[02:17:26.826] Timer tick, asking Buildbucket for the build status
[02:17:27.141] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:27.141] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:99:0) after 1m0s
[02:18:27.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:99:0)
[02:18:27.136] Timer tick, asking Buildbucket for the build status
[02:18:27.556] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:27.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:100:0) after 1m0s
[02:19:27.563] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:100:0)
[02:19:27.563] Timer tick, asking Buildbucket for the build status
[02:19:27.622] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:27.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:101:0) after 1m0s
[02:20:27.872] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:101:0)
[02:20:27.872] Timer tick, asking Buildbucket for the build status
[02:20:28.270] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:28.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:102:0) after 1m0s
[02:21:28.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:102:0)
[02:21:28.306] Timer tick, asking Buildbucket for the build status
[02:21:28.782] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:28.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:103:0) after 1m0s
[02:22:28.903] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:103:0)
[02:22:28.903] Timer tick, asking Buildbucket for the build status
[02:22:29.146] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:29.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:104:0) after 1m0s
[02:23:29.173] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:104:0)
[02:23:29.173] Timer tick, asking Buildbucket for the build status
[02:23:29.249] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:29.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:105:0) after 1m0s
[02:24:29.344] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:105:0)
[02:24:29.344] Timer tick, asking Buildbucket for the build status
[02:24:29.391] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:29.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:106:0) after 1m0s
[02:25:29.405] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:106:0)
[02:25:29.405] Timer tick, asking Buildbucket for the build status
[02:25:29.465] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:29.465] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:107:0) after 1m0s
[02:26:29.489] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:107:0)
[02:26:29.489] Timer tick, asking Buildbucket for the build status
[02:26:29.571] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:29.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:108:0) after 1m0s
[02:27:29.593] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:108:0)
[02:27:29.593] Timer tick, asking Buildbucket for the build status
[02:27:29.736] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:29.736] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:109:0) after 1m0s
[02:28:29.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:109:0)
[02:28:29.738] Timer tick, asking Buildbucket for the build status
[02:28:29.875] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:29.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:110:0) after 1m0s
[02:29:31.450] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:110:0)
[02:29:31.450] Timer tick, asking Buildbucket for the build status
[02:29:31.565] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:31.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:111:0) after 1m0s
[02:30:33.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:111:0)
[02:30:33.013] Timer tick, asking Buildbucket for the build status
[02:30:33.065] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:33.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:112:0) after 1m0s
[02:31:33.092] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:112:0)
[02:31:33.092] Timer tick, asking Buildbucket for the build status
[02:31:33.419] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:33.419] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:113:0) after 1m0s
[02:32:33.477] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:113:0)
[02:32:33.477] Timer tick, asking Buildbucket for the build status
[02:32:33.546] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:33.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:114:0) after 1m0s
[02:33:33.554] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:114:0)
[02:33:33.554] Timer tick, asking Buildbucket for the build status
[02:33:33.701] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:33.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:115:0) after 1m0s
[02:34:33.701] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:115:0)
[02:34:33.701] Timer tick, asking Buildbucket for the build status
[02:34:33.740] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:33.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:116:0) after 1m0s
[02:35:33.764] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:116:0)
[02:35:33.764] Timer tick, asking Buildbucket for the build status
[02:35:33.888] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:33.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:117:0) after 1m0s
[02:36:33.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:117:0)
[02:36:33.950] Timer tick, asking Buildbucket for the build status
[02:36:34.022] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:34.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:118:0) after 1m0s
[02:37:34.081] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:118:0)
[02:37:34.081] Timer tick, asking Buildbucket for the build status
[02:37:34.181] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:34.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:119:0) after 1m0s
[02:38:34.222] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:119:0)
[02:38:34.222] Timer tick, asking Buildbucket for the build status
[02:38:34.415] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:34.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:120:0) after 1m0s
[02:39:34.433] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:120:0)
[02:39:34.433] Timer tick, asking Buildbucket for the build status
[02:39:34.537] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:34.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:121:0) after 1m0s
[02:40:34.581] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:121:0)
[02:40:34.581] Timer tick, asking Buildbucket for the build status
[02:40:34.997] Build 8912803073239027696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:34.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078233135287794672:122:0) after 1m0s
[02:41:20.909] Received PubSub notification, asking Buildbucket for the build status
[02:41:21.609] Build 8912803073239027696: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:41:21.609] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558492879430861",
"created_by": "project:fuchsia",
"created_ts": "1558485644073690",
"failure_reason": "BUILD_FAILURE",
"id": "8912803073239027696",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"bceadcbb0ca8e81bf3cec11c74d877461018c9f2\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-c-2-x115\", \"branch\": \"refs/heads/master\", \"buildername\": \"clang\", \"builders\": [\"clang-linux-arm64\", \"clang-linux-x64\", \"clang-mac-x64\"], \"path_config\": \"generic\", \"recipe\": \"buildbucket_trigger\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"bceadcbb0ca8e81bf3cec11c74d877461018c9f2\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-c-2-x115\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4303-bae210e\"], \"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": "1558485652739759",
"status": "COMPLETED",
"status_changed_ts": "1558485653165676",
"tags": [
"builder:clang",
"buildset:commit/git/bceadcbb0ca8e81bf3cec11c74d877461018c9f2",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/bceadcbb0ca8e81bf3cec11c74d877461018c9f2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078233135287794672",
"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/8912803073239027696/+/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:44f5cd40e2819c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558492879619413",
"url": "https://ci.chromium.org/b/8912803073239027696",
"utcnow_ts": "1558492881574960"
}
}
[02:41:21.609] Invocation finished in 2h0m38.873962813s with status FAILED