[09:47:00.217] New invocation is queued and will start shortly
[09:47:01.477] Starting the invocation (attempt 1)
[09:47:01.582] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:47:01.582] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:47:01.582] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9078379959560019312",
"parameters_json": "{\"builder_name\":\"clang\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://fuchsia.googlesource.com/third_party/llvm-project\",\"revision\":\"a760e698405c2756d8901c11f31061b904746e2c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:clang",
"scheduler_invocation_id:9078379959560019312",
"scheduler_job_id:fuchsia/prod-clang",
"user_agent:luci-scheduler",
"buildset:commit/git/a760e698405c2756d8901c11f31061b904746e2c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a760e698405c2756d8901c11f31061b904746e2c",
"gitiles_ref:refs/heads/master"
]
}
[09:47:02.834] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"created_by": "project:fuchsia",
"created_ts": "1558345622070659",
"id": "8912949896950751248",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a760e698405c2756d8901c11f31061b904746e2c\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558345622590120",
"tags": [
"builder:clang",
"buildset:commit/git/a760e698405c2756d8901c11f31061b904746e2c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a760e698405c2756d8901c11f31061b904746e2c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078379959560019312",
"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/8912949896950751248/+/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": "1558345622590208",
"url": "https://ci.chromium.org/b/8912949896950751248",
"utcnow_ts": "1558345622795579"
}
}
[09:47:02.834] Task URL: https://ci.chromium.org/b/8912949896950751248
[09:47:02.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:2:0) after 1m0s
[09:47:10.404] Received PubSub notification, asking Buildbucket for the build status
[09:47:10.493] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:02.954] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:2:0)
[09:48:02.954] Timer tick, asking Buildbucket for the build status
[09:48:03.951] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:03.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:4:0) after 1m0s
[09:49:04.089] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:4:0)
[09:49:04.089] Timer tick, asking Buildbucket for the build status
[09:49:04.193] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:04.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:5:0) after 1m0s
[09:50:04.228] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:5:0)
[09:50:04.228] Timer tick, asking Buildbucket for the build status
[09:50:04.351] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:04.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:6:0) after 1m0s
[09:51:04.329] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:6:0)
[09:51:04.329] Timer tick, asking Buildbucket for the build status
[09:51:04.505] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:04.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:7:0) after 1m0s
[09:52:04.497] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:7:0)
[09:52:04.497] Timer tick, asking Buildbucket for the build status
[09:52:04.627] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:04.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:8:0) after 1m0s
[09:53:04.614] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:8:0)
[09:53:04.614] Timer tick, asking Buildbucket for the build status
[09:53:04.741] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:04.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:9:0) after 1m0s
[09:54:04.783] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:9:0)
[09:54:04.783] Timer tick, asking Buildbucket for the build status
[09:54:04.861] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:04.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:10:0) after 1m0s
[09:55:04.906] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:10:0)
[09:55:04.906] Timer tick, asking Buildbucket for the build status
[09:55:05.125] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:05.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:11:0) after 1m0s
[09:56:05.218] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:11:0)
[09:56:05.218] Timer tick, asking Buildbucket for the build status
[09:56:05.971] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:05.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:12:0) after 1m0s
[09:57:05.993] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:12:0)
[09:57:05.994] Timer tick, asking Buildbucket for the build status
[09:57:10.966] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:10.966] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:13:0) after 1m0s
[09:58:11.046] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:13:0)
[09:58:11.046] Timer tick, asking Buildbucket for the build status
[09:58:11.274] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:11.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:14:0) after 1m0s
[09:59:11.300] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:14:0)
[09:59:11.300] Timer tick, asking Buildbucket for the build status
[09:59:11.803] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:11.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:15:0) after 1m0s
[10:00:11.805] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:15:0)
[10:00:11.805] Timer tick, asking Buildbucket for the build status
[10:00:11.946] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:11.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:16:0) after 1m0s
[10:01:11.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:16:0)
[10:01:11.971] Timer tick, asking Buildbucket for the build status
[10:01:12.173] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:12.173] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:17:0) after 1m0s
[10:02:12.215] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:17:0)
[10:02:12.215] Timer tick, asking Buildbucket for the build status
[10:02:12.362] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:12.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:18:0) after 1m0s
[10:03:12.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:18:0)
[10:03:12.395] Timer tick, asking Buildbucket for the build status
[10:03:12.606] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:12.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:19:0) after 1m0s
[10:04:12.626] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:19:0)
[10:04:12.626] Timer tick, asking Buildbucket for the build status
[10:04:13.237] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:13.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:20:0) after 1m0s
[10:05:13.251] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:20:0)
[10:05:13.251] Timer tick, asking Buildbucket for the build status
[10:05:13.353] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:13.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:21:0) after 1m0s
[10:06:13.374] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:21:0)
[10:06:13.374] Timer tick, asking Buildbucket for the build status
[10:06:13.513] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:13.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:22:0) after 1m0s
[10:07:13.530] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:22:0)
[10:07:13.530] Timer tick, asking Buildbucket for the build status
[10:07:13.674] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:13.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:23:0) after 1m0s
[10:08:13.718] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:23:0)
[10:08:13.718] Timer tick, asking Buildbucket for the build status
[10:08:13.920] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:13.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:24:0) after 1m0s
[10:09:13.939] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:24:0)
[10:09:13.939] Timer tick, asking Buildbucket for the build status
[10:09:14.012] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:14.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:25:0) after 1m0s
[10:10:14.088] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:25:0)
[10:10:14.088] Timer tick, asking Buildbucket for the build status
[10:10:14.163] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:14.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:26:0) after 1m0s
[10:11:14.209] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:26:0)
[10:11:14.209] Timer tick, asking Buildbucket for the build status
[10:11:14.361] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:14.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:27:0) after 1m0s
[10:12:14.379] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:27:0)
[10:12:14.379] Timer tick, asking Buildbucket for the build status
[10:12:14.443] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:14.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:28:0) after 1m0s
[10:13:14.570] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:28:0)
[10:13:14.570] Timer tick, asking Buildbucket for the build status
[10:13:15.359] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:15.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:29:0) after 1m0s
[10:14:15.389] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:29:0)
[10:14:15.389] Timer tick, asking Buildbucket for the build status
[10:14:15.890] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:15.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:30:0) after 1m0s
[10:15:16.016] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:30:0)
[10:15:16.016] Timer tick, asking Buildbucket for the build status
[10:15:16.137] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:16.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:31:0) after 1m0s
[10:16:16.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:31:0)
[10:16:16.171] Timer tick, asking Buildbucket for the build status
[10:16:16.316] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:16.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:32:0) after 1m0s
[10:17:16.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:32:0)
[10:17:16.315] Timer tick, asking Buildbucket for the build status
[10:17:16.398] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:16.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:33:0) after 1m0s
[10:18:16.498] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:33:0)
[10:18:16.498] Timer tick, asking Buildbucket for the build status
[10:18:16.695] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:16.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:34:0) after 1m0s
[10:19:16.719] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:34:0)
[10:19:16.719] Timer tick, asking Buildbucket for the build status
[10:19:16.808] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:16.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:35:0) after 1m0s
[10:20:16.822] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:35:0)
[10:20:16.822] Timer tick, asking Buildbucket for the build status
[10:20:16.883] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:16.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:36:0) after 1m0s
[10:21:16.916] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:36:0)
[10:21:16.916] Timer tick, asking Buildbucket for the build status
[10:21:17.007] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:17.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:37:0) after 1m0s
[10:22:17.028] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:37:0)
[10:22:17.028] Timer tick, asking Buildbucket for the build status
[10:22:17.106] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:17.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:38:0) after 1m0s
[10:23:17.171] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:38:0)
[10:23:17.171] Timer tick, asking Buildbucket for the build status
[10:23:17.292] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:17.292] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:39:0) after 1m0s
[10:24:17.295] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:39:0)
[10:24:17.295] Timer tick, asking Buildbucket for the build status
[10:24:17.443] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:17.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:40:0) after 1m0s
[10:25:17.436] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:40:0)
[10:25:17.436] Timer tick, asking Buildbucket for the build status
[10:25:17.661] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:17.661] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:41:0) after 1m0s
[10:26:17.683] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:41:0)
[10:26:17.683] Timer tick, asking Buildbucket for the build status
[10:26:17.876] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:17.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:42:0) after 1m0s
[10:27:17.887] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:42:0)
[10:27:17.887] Timer tick, asking Buildbucket for the build status
[10:27:17.975] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:17.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:43:0) after 1m0s
[10:28:18.013] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:43:0)
[10:28:18.013] Timer tick, asking Buildbucket for the build status
[10:28:18.708] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:18.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:44:0) after 1m0s
[10:29:18.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:44:0)
[10:29:18.736] Timer tick, asking Buildbucket for the build status
[10:29:19.021] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:19.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:45:0) after 1m0s
[10:30:19.083] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:45:0)
[10:30:19.083] Timer tick, asking Buildbucket for the build status
[10:30:19.191] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:19.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:46:0) after 1m0s
[10:31:19.226] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:46:0)
[10:31:19.226] Timer tick, asking Buildbucket for the build status
[10:31:19.353] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:19.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:47:0) after 1m0s
[10:32:19.424] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:47:0)
[10:32:19.424] Timer tick, asking Buildbucket for the build status
[10:32:19.484] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:19.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:48:0) after 1m0s
[10:33:19.512] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:48:0)
[10:33:19.512] Timer tick, asking Buildbucket for the build status
[10:33:19.615] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:19.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:49:0) after 1m0s
[10:34:19.661] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:49:0)
[10:34:19.661] Timer tick, asking Buildbucket for the build status
[10:34:20.003] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:20.003] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:50:0) after 1m0s
[10:35:19.994] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:50:0)
[10:35:19.994] Timer tick, asking Buildbucket for the build status
[10:35:20.268] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:20.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:51:0) after 1m0s
[10:36:20.383] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:51:0)
[10:36:20.383] Timer tick, asking Buildbucket for the build status
[10:36:20.454] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:20.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:52:0) after 1m0s
[10:37:20.490] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:52:0)
[10:37:20.490] Timer tick, asking Buildbucket for the build status
[10:37:20.653] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:20.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:53:0) after 1m0s
[10:38:20.686] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:53:0)
[10:38:20.686] Timer tick, asking Buildbucket for the build status
[10:38:20.753] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:20.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:54:0) after 1m0s
[10:39:20.784] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:54:0)
[10:39:20.784] Timer tick, asking Buildbucket for the build status
[10:39:21.013] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:21.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:55:0) after 1m0s
[10:40:21.084] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:55:0)
[10:40:21.084] Timer tick, asking Buildbucket for the build status
[10:40:21.124] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:21.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:56:0) after 1m0s
[10:41:21.148] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:56:0)
[10:41:21.148] Timer tick, asking Buildbucket for the build status
[10:41:21.716] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:21.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:57:0) after 1m0s
[10:42:21.729] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:57:0)
[10:42:21.729] Timer tick, asking Buildbucket for the build status
[10:42:21.889] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:21.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:58:0) after 1m0s
[10:43:21.912] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:58:0)
[10:43:21.912] Timer tick, asking Buildbucket for the build status
[10:43:22.282] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:22.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:59:0) after 1m0s
[10:44:22.330] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:59:0)
[10:44:22.330] Timer tick, asking Buildbucket for the build status
[10:44:22.395] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:22.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:60:0) after 1m0s
[10:45:22.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:60:0)
[10:45:22.427] Timer tick, asking Buildbucket for the build status
[10:45:22.483] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:22.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:61:0) after 1m0s
[10:46:22.494] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:61:0)
[10:46:22.494] Timer tick, asking Buildbucket for the build status
[10:46:23.345] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:23.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:62:0) after 1m0s
[10:47:23.431] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:62:0)
[10:47:23.431] Timer tick, asking Buildbucket for the build status
[10:47:23.573] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:23.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:63:0) after 1m0s
[10:48:23.590] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:63:0)
[10:48:23.590] Timer tick, asking Buildbucket for the build status
[10:48:23.697] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:23.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:64:0) after 1m0s
[10:49:23.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:64:0)
[10:49:23.720] Timer tick, asking Buildbucket for the build status
[10:49:23.993] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:23.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:65:0) after 1m0s
[10:50:24.017] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:65:0)
[10:50:24.017] Timer tick, asking Buildbucket for the build status
[10:50:24.064] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:24.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:66:0) after 1m0s
[10:51:24.086] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:66:0)
[10:51:24.086] Timer tick, asking Buildbucket for the build status
[10:51:24.438] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:24.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:67:0) after 1m0s
[10:52:24.453] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:67:0)
[10:52:24.453] Timer tick, asking Buildbucket for the build status
[10:52:24.521] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:24.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:68:0) after 1m0s
[10:53:24.565] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:68:0)
[10:53:24.565] Timer tick, asking Buildbucket for the build status
[10:53:24.647] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:24.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:69:0) after 1m0s
[10:54:24.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:69:0)
[10:54:24.698] Timer tick, asking Buildbucket for the build status
[10:54:24.793] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:24.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:70:0) after 1m0s
[10:55:24.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:70:0)
[10:55:24.831] Timer tick, asking Buildbucket for the build status
[10:55:24.908] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:24.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:71:0) after 1m0s
[10:56:24.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:71:0)
[10:56:24.933] Timer tick, asking Buildbucket for the build status
[10:56:25.406] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:25.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:72:0) after 1m0s
[10:57:25.407] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:72:0)
[10:57:25.407] Timer tick, asking Buildbucket for the build status
[10:57:25.529] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:25.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:73:0) after 1m0s
[10:58:25.609] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:73:0)
[10:58:25.609] Timer tick, asking Buildbucket for the build status
[10:58:25.680] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:25.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:74:0) after 1m0s
[10:59:25.707] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:74:0)
[10:59:25.707] Timer tick, asking Buildbucket for the build status
[10:59:25.760] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:25.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:75:0) after 1m0s
[11:00:25.780] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:75:0)
[11:00:25.780] Timer tick, asking Buildbucket for the build status
[11:00:26.457] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:26.458] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:76:0) after 1m0s
[11:01:26.595] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:76:0)
[11:01:26.595] Timer tick, asking Buildbucket for the build status
[11:01:26.715] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:26.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:77:0) after 1m0s
[11:02:26.738] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:77:0)
[11:02:26.738] Timer tick, asking Buildbucket for the build status
[11:02:27.492] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:27.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:78:0) after 1m0s
[11:03:27.517] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:78:0)
[11:03:27.517] Timer tick, asking Buildbucket for the build status
[11:03:27.644] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:27.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:79:0) after 1m0s
[11:04:27.693] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:79:0)
[11:04:27.693] Timer tick, asking Buildbucket for the build status
[11:04:30.020] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:30.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:80:0) after 1m0s
[11:05:30.024] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:80:0)
[11:05:30.024] Timer tick, asking Buildbucket for the build status
[11:05:30.074] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:30.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:81:0) after 1m0s
[11:06:30.097] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:81:0)
[11:06:30.097] Timer tick, asking Buildbucket for the build status
[11:06:30.535] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:30.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:82:0) after 1m0s
[11:07:30.566] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:82:0)
[11:07:30.566] Timer tick, asking Buildbucket for the build status
[11:07:30.621] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:30.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:83:0) after 1m0s
[11:08:30.648] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:83:0)
[11:08:30.648] Timer tick, asking Buildbucket for the build status
[11:08:30.723] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:30.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:84:0) after 1m0s
[11:09:30.749] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:84:0)
[11:09:30.749] Timer tick, asking Buildbucket for the build status
[11:09:31.832] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:31.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:85:0) after 1m0s
[11:10:31.847] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:85:0)
[11:10:31.847] Timer tick, asking Buildbucket for the build status
[11:10:32.038] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:32.038] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:86:0) after 1m0s
[11:11:32.064] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:86:0)
[11:11:32.064] Timer tick, asking Buildbucket for the build status
[11:11:32.164] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:32.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:87:0) after 1m0s
[11:12:32.191] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:87:0)
[11:12:32.191] Timer tick, asking Buildbucket for the build status
[11:12:32.413] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:32.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:88:0) after 1m0s
[11:13:32.438] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:88:0)
[11:13:32.438] Timer tick, asking Buildbucket for the build status
[11:13:32.495] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:32.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:89:0) after 1m0s
[11:14:32.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:89:0)
[11:14:32.567] Timer tick, asking Buildbucket for the build status
[11:14:32.863] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:32.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:90:0) after 1m0s
[11:15:32.862] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:90:0)
[11:15:32.862] Timer tick, asking Buildbucket for the build status
[11:15:33.202] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:33.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:91:0) after 1m0s
[11:16:33.220] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:91:0)
[11:16:33.220] Timer tick, asking Buildbucket for the build status
[11:16:33.327] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:33.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:92:0) after 1m0s
[11:17:33.318] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:92:0)
[11:17:33.318] Timer tick, asking Buildbucket for the build status
[11:17:33.617] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:33.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:93:0) after 1m0s
[11:18:33.645] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:93:0)
[11:18:33.645] Timer tick, asking Buildbucket for the build status
[11:18:33.685] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:33.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:94:0) after 1m0s
[11:19:33.708] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:94:0)
[11:19:33.708] Timer tick, asking Buildbucket for the build status
[11:19:33.795] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:33.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:95:0) after 1m0s
[11:20:33.828] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:95:0)
[11:20:33.828] Timer tick, asking Buildbucket for the build status
[11:20:34.294] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:34.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:96:0) after 1m0s
[11:21:34.315] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:96:0)
[11:21:34.315] Timer tick, asking Buildbucket for the build status
[11:21:34.369] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:34.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:97:0) after 1m0s
[11:22:34.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:97:0)
[11:22:34.395] Timer tick, asking Buildbucket for the build status
[11:22:34.945] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:34.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:98:0) after 1m0s
[11:23:34.968] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:98:0)
[11:23:34.968] Timer tick, asking Buildbucket for the build status
[11:23:35.033] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:35.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:99:0) after 1m0s
[11:24:35.078] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:99:0)
[11:24:35.078] Timer tick, asking Buildbucket for the build status
[11:24:35.448] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:35.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:100:0) after 1m0s
[11:25:35.512] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:100:0)
[11:25:35.512] Timer tick, asking Buildbucket for the build status
[11:25:35.892] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:35.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:101:0) after 1m0s
[11:26:35.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:101:0)
[11:26:35.950] Timer tick, asking Buildbucket for the build status
[11:26:36.060] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:36.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:102:0) after 1m0s
[11:27:36.091] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:102:0)
[11:27:36.091] Timer tick, asking Buildbucket for the build status
[11:27:36.144] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:36.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:103:0) after 1m0s
[11:28:36.250] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:103:0)
[11:28:36.250] Timer tick, asking Buildbucket for the build status
[11:28:36.323] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:36.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:104:0) after 1m0s
[11:29:36.351] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:104:0)
[11:29:36.351] Timer tick, asking Buildbucket for the build status
[11:29:36.404] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:36.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:105:0) after 1m0s
[11:30:36.432] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:105:0)
[11:30:36.432] Timer tick, asking Buildbucket for the build status
[11:30:36.603] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:36.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:106:0) after 1m0s
[11:31:36.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:106:0)
[11:31:36.674] Timer tick, asking Buildbucket for the build status
[11:31:37.308] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:37.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:107:0) after 1m0s
[11:32:37.393] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:107:0)
[11:32:37.393] Timer tick, asking Buildbucket for the build status
[11:32:37.449] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:37.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:108:0) after 1m0s
[11:33:37.484] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:108:0)
[11:33:37.484] Timer tick, asking Buildbucket for the build status
[11:33:37.646] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:37.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:109:0) after 1m0s
[11:34:37.662] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:109:0)
[11:34:37.662] Timer tick, asking Buildbucket for the build status
[11:34:37.794] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:37.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:110:0) after 1m0s
[11:35:37.778] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:110:0)
[11:35:37.778] Timer tick, asking Buildbucket for the build status
[11:35:37.863] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:37.863] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:111:0) after 1m0s
[11:36:37.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:111:0)
[11:36:37.910] Timer tick, asking Buildbucket for the build status
[11:36:37.972] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:37.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:112:0) after 1m0s
[11:37:37.996] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:112:0)
[11:37:37.996] Timer tick, asking Buildbucket for the build status
[11:37:38.073] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:38.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:113:0) after 1m0s
[11:38:38.120] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:113:0)
[11:38:38.120] Timer tick, asking Buildbucket for the build status
[11:38:38.251] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:38.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:114:0) after 1m0s
[11:39:38.355] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:114:0)
[11:39:38.355] Timer tick, asking Buildbucket for the build status
[11:39:38.450] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:38.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:115:0) after 1m0s
[11:40:38.553] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:115:0)
[11:40:38.553] Timer tick, asking Buildbucket for the build status
[11:40:39.156] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:39.156] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:116:0) after 1m0s
[11:41:39.199] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:116:0)
[11:41:39.199] Timer tick, asking Buildbucket for the build status
[11:41:39.379] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:39.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:117:0) after 1m0s
[11:42:39.427] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:117:0)
[11:42:39.427] Timer tick, asking Buildbucket for the build status
[11:42:39.472] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:39.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:118:0) after 1m0s
[11:43:39.532] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:118:0)
[11:43:39.532] Timer tick, asking Buildbucket for the build status
[11:43:39.672] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:39.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:119:0) after 1m0s
[11:44:39.698] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:119:0)
[11:44:39.698] Timer tick, asking Buildbucket for the build status
[11:44:39.814] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:39.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:120:0) after 1m0s
[11:45:39.838] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:120:0)
[11:45:39.838] Timer tick, asking Buildbucket for the build status
[11:45:39.943] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:39.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:121:0) after 1m0s
[11:46:40.044] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:121:0)
[11:46:40.044] Timer tick, asking Buildbucket for the build status
[11:46:40.126] Build 8912949896950751248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:40.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang:9078379959560019312:122:0) after 1m0s
[11:47:33.219] Received PubSub notification, asking Buildbucket for the build status
[11:47:33.381] Build 8912949896950751248: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:47:33.381] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "AUTO",
"completed_ts": "1558352852916304",
"created_by": "project:fuchsia",
"created_ts": "1558345622070659",
"failure_reason": "BUILD_FAILURE",
"id": "8912949896950751248",
"parameters_json": "{\"builder_name\": \"clang\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://fuchsia.googlesource.com/third_party/llvm-project\", \"revision\": \"a760e698405c2756d8901c11f31061b904746e2c\"}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}, \"properties\": {\"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"fuchsia-prod-1-us-central1-b-1-85ou\", \"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\": \"a760e698405c2756d8901c11f31061b904746e2c\", \"timeout\": 7200.0}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"1\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-1-us-central1-b-1-85ou\"], \"image\": [\"fuchsia-debian-9-drawfork-v20190501-f4ac45d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-1\"], \"os\": [\"Debian\", \"Debian-9.5\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4302-e8210c4\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"Step Timeout: Step('buildbucket.run.collect') timed out after 7200\"}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1558345628935123",
"status": "COMPLETED",
"status_changed_ts": "1558345630165718",
"tags": [
"builder:clang",
"buildset:commit/git/a760e698405c2756d8901c11f31061b904746e2c",
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/llvm-project/+/a760e698405c2756d8901c11f31061b904746e2c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078379959560019312",
"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/8912949896950751248/+/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:44ed74adc436c910",
"user_agent:luci-scheduler"
],
"updated_ts": "1558352852984010",
"url": "https://ci.chromium.org/b/8912949896950751248",
"utcnow_ts": "1558352853353747"
}
}
[11:47:33.381] Invocation finished in 2h0m33.183233401s with status FAILED